-- Logs begin at Thu 2019-02-14 21:11:59 AEDT, end at Sun 2025-12-07 04:57:53 AEDT. -- Dec 07 04:56:02 volumio volumio[1008]: info: Initializing connection to go-librespot Websocket Dec 07 04:56:02 volumio volumio[1008]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 07 04:56:05 volumio volumio[1008]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 07 04:56:05 volumio volumio[1008]: info: Initializing connection to go-librespot Websocket Dec 07 04:56:05 volumio volumio[1008]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 07 04:56:08 volumio volumio[1008]: info: Initializing connection to go-librespot Websocket Dec 07 04:56:08 volumio volumio[1008]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 07 04:56:08 volumio volumio[1008]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Dec 07 04:56:08 volumio volumio[1008]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 07 04:56:08 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 07 04:56:08 volumio sudo[2365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Dec 07 04:56:08 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 04:56:09 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Dec 07 04:56:09 volumio volumio[1008]: info: Check plugin dependencies Dec 07 04:56:09 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 07 04:56:09 volumio volumio[1008]: info: Rename folder Dec 07 04:56:09 volumio volumio[1008]: info: Folder /tmp/downloaded_plugin.zip removed Dec 07 04:56:09 volumio volumio[1008]: info: Move to category Dec 07 04:56:10 volumio volumio[1008]: info: Checking if install.sh is present Dec 07 04:56:10 volumio volumio[1008]: info: Executing install.sh Dec 07 04:56:10 volumio sudo[2421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Dec 07 04:56:10 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 07 04:56:10 volumio volumio[1008]: info: Installing Go-librespot Dec 07 04:56:10 volumio volumio[1008]: info: Checking old vollibrespot installs Dec 07 04:56:10 volumio systemd[1]: Reloading. Dec 07 04:56:11 volumio 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. Dec 07 04:56:11 volumio volumio[1008]: info: Initializing connection to go-librespot Websocket Dec 07 04:56:11 volumio volumio[1008]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 07 04:56:11 volumio volumio[1008]: info: Downloading daemon Dec 07 04:56:14 volumio volumio[1008]: info: Creating directories Dec 07 04:56:14 volumio volumio[1008]: info: Creating data directory Dec 07 04:56:14 volumio volumio[1008]: info: Creating Start Script Dec 07 04:56:14 volumio systemd[1]: Reloading. Dec 07 04:56:14 volumio 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. Dec 07 04:56:14 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root Dec 07 04:56:14 volumio volumio[1008]: Plugin install end detected on script Dec 07 04:56:14 volumio volumio[1008]: info: Install script completed Dec 07 04:56:14 volumio volumio[1008]: info: Done installing plugin. Dec 07 04:56:14 volumio volumio[1008]: info: Enabling plugin spop Dec 07 04:56:14 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 07 04:56:14 volumio volumio[1008]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.3.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.4.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Dec 07 04:56:14 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 07 04:56:14 volumio volumio[1008]: info: Folder /tmp/plugins removed Dec 07 04:56:14 volumio volumio[1008]: info: Folder /tmp/downloaded_plugin.zip removed Dec 07 04:56:14 volumio volumio[1008]: info: Folder /data/temp removed Dec 07 04:56:55 volumio volumio[1008]: info: CoreCommandRouter::volumioGetQueue Dec 07 04:56:55 volumio volumio[1008]: info: CoreStateMachine::getQueue Dec 07 04:56:55 volumio volumio[1008]: info: CorePlayQueue::getQueue Dec 07 04:57:06 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 07 04:57:06 volumio volumio[1008]: info: Preload queue cleared Dec 07 04:57:27 volumio ntpd[786]: 220.158.215.21 local addr 192.168.0.63 -> Dec 07 04:57:28 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 04:57:28 volumio volumio[1008]: info: CURURI: playlists Dec 07 04:57:28 volumio volumio[1008]: info: Listing playlists Dec 07 04:57:28 volumio volumio[1008]: info: Preload queue cleared Dec 07 04:57:31 volumio ntpd[786]: 172.105.179.71 local addr 192.168.0.63 -> Dec 07 04:57:35 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 07 04:57:35 volumio volumio[1008]: info: CURURI: music-library Dec 07 04:57:35 volumio volumio[1008]: info: Preload queue cleared Dec 07 04:57:45 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 07 04:57:45 volumio volumio[1008]: info: Preload queue cleared Dec 07 04:57:49 volumio volumio[1008]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 07 04:57:53 volumio volumio[1008]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 04:57:53 volumio volumio[1008]: TypeError: Cannot read property 'length' of undefined Dec 07 04:57:53 volumio volumio[1008]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Dec 07 04:57:53 volumio volumio[1008]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Dec 07 04:57:53 volumio volumio[1008]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Dec 07 04:57:53 volumio volumio[1008]: at Parser.emit (events.js:315:20) Dec 07 04:57:53 volumio volumio[1008]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Dec 07 04:57:53 volumio volumio[1008]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Dec 07 04:57:53 volumio volumio[1008]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Dec 07 04:57:53 volumio volumio[1008]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Dec 07 04:57:53 volumio volumio[1008]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Dec 07 04:57:53 volumio volumio[1008]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Dec 07 04:57:53 volumio volumio[1008]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Dec 07 04:57:53 volumio volumio[1008]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Dec 07 04:57:53 volumio volumio[1008]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Dec 07 04:57:53 volumio volumio[1008]: at IncomingMessage.emit (events.js:327:22) Dec 07 04:57:53 volumio volumio[1008]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 07 04:57:53 volumio volumio[1008]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Dec 07 04:57:53 volumio volumio[1008]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 07 04:57:53 volumio sudo[2529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-07 04:56 Dec 07 04:57:53 volumio sudo[2529]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"