-- Logs begin at Sun 2024-03-31 08:05:04 AWST, end at Mon 2025-01-13 18:15:34 AWST. -- Jan 13 18:14:04 brodrics-ask volumio[778]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] Jan 13 18:14:06 brodrics-ask volumio[778]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] Jan 13 18:14:07 brodrics-ask volumio[778]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] Jan 13 18:14:08 brodrics-ask volumio[778]: info: Fetched 13.7 MB in 15s (901 kB/s) Jan 13 18:14:11 brodrics-ask volumio[778]: info: Reading package lists... Jan 13 18:14:11 brodrics-ask volumio[778]: info: Reading package lists... Jan 13 18:14:12 brodrics-ask volumio[778]: info: Building dependency tree... Jan 13 18:14:12 brodrics-ask volumio[778]: info: Reading state information... Jan 13 18:14:13 brodrics-ask volumio[778]: info: The following additional packages will be installed: Jan 13 18:14:13 brodrics-ask volumio[778]: info: libexif12 Jan 13 18:14:13 brodrics-ask volumio[778]: info: The following NEW packages will be installed: Jan 13 18:14:13 brodrics-ask volumio[778]: info: libexif12 minidlna Jan 13 18:14:14 brodrics-ask volumio[778]: info: 0 upgraded, 2 newly installed, 0 to remove and 119 not upgraded. Jan 13 18:14:14 brodrics-ask volumio[778]: info: Need to get 446 kB of archives. Jan 13 18:14:14 brodrics-ask volumio[778]: info: After this operation, 2329 kB of additional disk space will be used. Jan 13 18:14:14 brodrics-ask volumio[778]: info: Get:1 http://mirror.aarnet.edu.au/pub/raspbian/raspbian buster/main armhf libexif12 armhf 0.6.21-5.1+deb10u5 [313 kB] Jan 13 18:14:14 brodrics-ask volumio[778]: info: Get:2 http://mirror.aarnet.edu.au/pub/raspbian/raspbian buster/main armhf minidlna armhf 1.2.1+dfsg-2+deb10u4 [132 kB] Jan 13 18:14:15 brodrics-ask volumio[778]: info: Fetched 446 kB in 2s (268 kB/s) Jan 13 18:14:16 brodrics-ask volumio[778]: info: Selecting previously unselected package libexif12:armhf. Jan 13 18:14:16 brodrics-ask volumiologrotate[615]: ls: cannot access "/var/log/samba/log.wb-BRODRIC'S": No such file or directory Jan 13 18:14:16 brodrics-ask volumiologrotate[615]: ls: cannot access 'ASK': No such file or directory Jan 13 18:14:17 brodrics-ask volumio[778]: [620B blob data] Jan 13 18:14:17 brodrics-ask volumio[778]: info: Preparing to unpack .../libexif12_0.6.21-5.1+deb10u5_armhf.deb ... Jan 13 18:14:17 brodrics-ask volumio[778]: info: Unpacking libexif12:armhf (0.6.21-5.1+deb10u5) ... Jan 13 18:14:17 brodrics-ask volumio[778]: info: Selecting previously unselected package minidlna. Jan 13 18:14:17 brodrics-ask volumio[778]: info: Preparing to unpack .../minidlna_1.2.1+dfsg-2+deb10u4_armhf.deb ... Jan 13 18:14:17 brodrics-ask volumio[778]: info: Unpacking minidlna (1.2.1+dfsg-2+deb10u4) ... Jan 13 18:14:18 brodrics-ask volumio[778]: info: Setting up libexif12:armhf (0.6.21-5.1+deb10u5) ... Jan 13 18:14:18 brodrics-ask volumio[778]: info: Setting up minidlna (1.2.1+dfsg-2+deb10u4) ... Jan 13 18:14:19 brodrics-ask groupadd[2222]: group added to /etc/group: name=minidlna, GID=123 Jan 13 18:14:19 brodrics-ask groupadd[2222]: group added to /etc/gshadow: name=minidlna Jan 13 18:14:19 brodrics-ask groupadd[2222]: new group: name=minidlna, GID=123 Jan 13 18:14:19 brodrics-ask useradd[2226]: new user: name=minidlna, UID=118, GID=123, home=/var/lib/minidlna, shell=/usr/sbin/nologin Jan 13 18:14:19 brodrics-ask usermod[2232]: change user 'minidlna' password Jan 13 18:14:19 brodrics-ask chage[2237]: changed password expiry for minidlna Jan 13 18:14:19 brodrics-ask chfn[2240]: changed user 'minidlna' information Jan 13 18:14:19 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:19 brodrics-ask 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 18:14:19 brodrics-ask systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 13 18:14:19 brodrics-ask systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 13 18:14:20 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:20 brodrics-ask 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 18:14:21 brodrics-ask volumio[778]: info: Created symlink /etc/systemd/system/multi-user.target.wants/minidlna.service → /lib/systemd/system/minidlna.service. Jan 13 18:14:21 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:21 brodrics-ask 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 18:14:21 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:21 brodrics-ask 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 18:14:22 brodrics-ask systemd[1]: Starting MiniDLNA lightweight DLNA/UPnP-AV server... Jan 13 18:14:22 brodrics-ask systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jan 13 18:14:22 brodrics-ask volumio[778]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Jan 13 18:14:23 brodrics-ask volumio[778]: info: Processing triggers for systemd (241-7~deb10u9+rpi1) ... Jan 13 18:14:23 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:23 brodrics-ask 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 18:14:24 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:24 brodrics-ask 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 18:14:25 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:25 brodrics-ask 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 18:14:25 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:26 brodrics-ask 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 18:14:26 brodrics-ask systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... Jan 13 18:14:26 brodrics-ask systemd[1]: minidlna.service: Succeeded. Jan 13 18:14:26 brodrics-ask systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. Jan 13 18:14:26 brodrics-ask volumio[778]: info: Creating systemd unit /etc/systemd/system/minidlna.service Jan 13 18:14:26 brodrics-ask systemd[1]: Reloading. Jan 13 18:14:26 brodrics-ask 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 18:14:27 brodrics-ask volumio[778]: info: Setting values for "network_interface" and "model_number" in /data/plugins/music_service/minidlna/config.json Jan 13 18:14:27 brodrics-ask volumio[778]: info: Setting permissions to MiniDLNA folders Jan 13 18:14:27 brodrics-ask sudo[1924]: pam_unix(sudo:session): session closed for user root Jan 13 18:14:27 brodrics-ask volumio[778]: info: Install script completed Jan 13 18:14:27 brodrics-ask volumio[778]: info: Adding reference to registry Jan 13 18:14:27 brodrics-ask volumio[778]: info: Done installing plugin. Jan 13 18:14:27 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 18:14:27 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 13 18:14:27 brodrics-ask volumio[778]: info: Folder /tmp/plugins removed Jan 13 18:14:27 brodrics-ask volumio[778]: info: Folder /tmp/downloaded_plugin.zip removed Jan 13 18:14:27 brodrics-ask volumio[778]: Plugin install end detected on script Jan 13 18:14:27 brodrics-ask volumio[778]: info: Folder /data/temp removed Jan 13 18:14:37 brodrics-ask volumio[778]: info: Enabling plugin minidlna Jan 13 18:14:37 brodrics-ask volumio[778]: info: Loading plugin "minidlna"... Jan 13 18:14:37 brodrics-ask volumio[778]: info: PLUGIN START: minidlna Jan 13 18:14:37 brodrics-ask volumio[778]: info: Loading i18n strings for locale en Jan 13 18:14:37 brodrics-ask volumio[778]: info: minidlna: Found miniDLNA version 1.2.1 Jan 13 18:14:37 brodrics-ask volumio[778]: info: minidlna: /data/minidlna.conf written Jan 13 18:14:37 brodrics-ask volumio[778]: info: minidlna: Starting minidlna.service Jan 13 18:14:37 brodrics-ask sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 13 18:14:37 brodrics-ask sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:14:37 brodrics-ask systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Jan 13 18:14:37 brodrics-ask sudo[2439]: pam_unix(sudo:session): session closed for user root Jan 13 18:14:37 brodrics-ask volumio[778]: info: minidlna: systemctl start minidlna.service succeeded. Jan 13 18:14:37 brodrics-ask volumio[778]: info: Done. Jan 13 18:14:42 brodrics-ask volumio[778]: info: Enabling plugin minidlna Jan 13 18:14:42 brodrics-ask volumio[778]: info: Loading plugin "minidlna"... Jan 13 18:14:42 brodrics-ask volumio[778]: info: Applying required configuration parameters for plugin minidlna Jan 13 18:14:42 brodrics-ask volumio[778]: info: PLUGIN START: minidlna Jan 13 18:14:42 brodrics-ask volumio[778]: info: Loading i18n strings for locale en Jan 13 18:14:42 brodrics-ask volumio[778]: info: minidlna: Found miniDLNA version 1.2.1 Jan 13 18:14:42 brodrics-ask volumio[778]: info: minidlna: Starting minidlna.service Jan 13 18:14:42 brodrics-ask sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jan 13 18:14:42 brodrics-ask sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 18:14:42 brodrics-ask sudo[2450]: pam_unix(sudo:session): session closed for user root Jan 13 18:14:42 brodrics-ask volumio[778]: info: minidlna: systemctl start minidlna.service succeeded. Jan 13 18:14:42 brodrics-ask volumio[778]: info: Done. Jan 13 18:14:51 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 18:14:51 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:15 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 18:15:15 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:16 brodrics-ask volumiologrotate[615]: ls: cannot access "/var/log/samba/log.wb-BRODRIC'S": No such file or directory Jan 13 18:15:16 brodrics-ask volumiologrotate[615]: ls: cannot access 'ASK': No such file or directory Jan 13 18:15:17 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 18:15:17 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:24 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::ClearQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::stop Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::clearPlayQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::saveQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreCommandRouter::volumioPushQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::addQueueItems Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::addQueueItems Jan 13 18:15:24 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:24 brodrics-ask volumio[778]: info: Adding Item to queue: upnp/folder/http://10.1.1.64:8200/ctl/ContentDir@64 Jan 13 18:15:24 brodrics-ask volumio[778]: info: Exploding uri upnp/folder/http://10.1.1.64:8200/ctl/ContentDir@64 in service upnp_browser Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreCommandRouter::volumioPushQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::saveQueue Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::updateTrackBlock Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::getTrackBlock Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreCommandRouter::volumioPlay Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::play index 0 Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::stop Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::play index undefined Jan 13 18:15:24 brodrics-ask volumio[778]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 13 18:15:24 brodrics-ask volumio[778]: info: CorePlayQueue::getTrack 0 Jan 13 18:15:30 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 18:15:30 brodrics-ask volumio[778]: info: Preload queue cleared Jan 13 18:15:33 brodrics-ask ntpd[725]: 172.105.168.117 local addr 10.1.1.64 -> Jan 13 18:15:33 brodrics-ask volumio[778]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jan 13 18:15:33 brodrics-ask volumio[778]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 18:15:33 brodrics-ask volumio[778]: TypeError: Cannot read property 'length' of undefined Jan 13 18:15:33 brodrics-ask volumio[778]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jan 13 18:15:33 brodrics-ask volumio[778]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jan 13 18:15:33 brodrics-ask volumio[778]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jan 13 18:15:33 brodrics-ask volumio[778]: at Parser.emit (events.js:315:20) Jan 13 18:15:33 brodrics-ask volumio[778]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jan 13 18:15:33 brodrics-ask volumio[778]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jan 13 18:15:33 brodrics-ask volumio[778]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jan 13 18:15:33 brodrics-ask volumio[778]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jan 13 18:15:33 brodrics-ask volumio[778]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jan 13 18:15:33 brodrics-ask volumio[778]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jan 13 18:15:33 brodrics-ask volumio[778]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jan 13 18:15:33 brodrics-ask volumio[778]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jan 13 18:15:33 brodrics-ask volumio[778]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jan 13 18:15:33 brodrics-ask volumio[778]: at IncomingMessage.emit (events.js:327:22) Jan 13 18:15:33 brodrics-ask volumio[778]: at endReadableNT (internal/streams/readable.js:1327:12) Jan 13 18:15:33 brodrics-ask volumio[778]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 18:15:33 brodrics-ask volumio[778]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 18:15:34 brodrics-ask sudo[2523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 18:14 Jan 13 18:15:34 brodrics-ask sudo[2523]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="bladelius" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 22 Feb 2024 11:40:29 AM CET" VOLUMIO_VERSION="3.605" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d3bdd0b3fc9d786f35abf7517805f755"