-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Fri 2025-03-14 08:19:42 EET. -- Mar 14 08:18:00 volumio volumio[1322]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf Mar 14 08:18:02 volumio volumio[1322]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/minidlna/volumio/buster/armhf Mar 14 08:18:02 volumio volumio[1322]: info: Folder /tmp/plugins removed Mar 14 08:18:02 volumio volumio[1322]: info: Check plugin dependencies Mar 14 08:18:02 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 14 08:18:02 volumio volumio[1322]: info: Checking if plugin already exists Mar 14 08:18:02 volumio volumio[1322]: info: Rename folder Mar 14 08:18:02 volumio volumio[1322]: info: Folder /tmp/downloaded_plugin.zip removed Mar 14 08:18:02 volumio volumio[1322]: info: Move to category Mar 14 08:18:02 volumio volumio[1322]: info: Checking if install.sh is present Mar 14 08:18:02 volumio volumio[1322]: info: Executing install.sh Mar 14 08:18:02 volumio sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/minidlna/install.sh Mar 14 08:18:02 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:18:02 volumio volumio[1322]: info: Completing "UIConfig.json" Mar 14 08:18:02 volumio volumio[1322]: info: Installing MiniDLNA Mar 14 08:18:02 volumio volumio[1322]: info: Get:1 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] Mar 14 08:18:02 volumio volumio[1322]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Mar 14 08:18:02 volumio volumio[1322]: info: Get:3 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Mar 14 08:18:03 volumio volumio[1322]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] Mar 14 08:18:03 volumio volumio[1322]: info: Fetched 469 kB in 1s (641 kB/s) Mar 14 08:18:04 volumio volumio[1322]: info: Reading package lists... Mar 14 08:18:04 volumio volumio[1322]: info: Reading package lists... Mar 14 08:18:04 volumio volumio[1322]: info: Building dependency tree... Mar 14 08:18:04 volumio volumio[1322]: info: Reading state information... Mar 14 08:18:05 volumio volumio[1322]: info: The following additional packages will be installed: Mar 14 08:18:05 volumio volumio[1322]: info: libexif12 Mar 14 08:18:05 volumio volumio[1322]: info: The following NEW packages will be installed: Mar 14 08:18:05 volumio volumio[1322]: info: libexif12 minidlna Mar 14 08:18:05 volumio volumio[1322]: info: 0 upgraded, 2 newly installed, 0 to remove and 2 not upgraded. Mar 14 08:18:05 volumio volumio[1322]: info: Need to get 446 kB of archives. Mar 14 08:18:05 volumio volumio[1322]: info: After this operation, 2329 kB of additional disk space will be used. Mar 14 08:18:05 volumio volumio[1322]: info: Get:1 http://raspbian.mirror.vu.lt/raspbian/raspbian buster/main armhf libexif12 armhf 0.6.21-5.1+deb10u5 [313 kB] Mar 14 08:18:05 volumio volumio[1322]: info: Get:2 http://raspbian.mirror.vu.lt/raspbian/raspbian buster/main armhf minidlna armhf 1.2.1+dfsg-2+deb10u4 [132 kB] Mar 14 08:18:05 volumio volumio[1322]: info: Fetched 446 kB in 0s (896 kB/s) Mar 14 08:18:06 volumio volumio[1322]: info: Selecting previously unselected package libexif12:armhf. Mar 14 08:18:06 volumio volumio[1322]: [620B blob data] Mar 14 08:18:06 volumio volumio[1322]: info: Preparing to unpack .../libexif12_0.6.21-5.1+deb10u5_armhf.deb ... Mar 14 08:18:06 volumio volumio[1322]: info: Unpacking libexif12:armhf (0.6.21-5.1+deb10u5) ... Mar 14 08:18:06 volumio volumio[1322]: info: Selecting previously unselected package minidlna. Mar 14 08:18:06 volumio volumio[1322]: info: Preparing to unpack .../minidlna_1.2.1+dfsg-2+deb10u4_armhf.deb ... Mar 14 08:18:06 volumio volumio[1322]: info: Unpacking minidlna (1.2.1+dfsg-2+deb10u4) ... Mar 14 08:18:08 volumio volumio[1322]: info: Setting up libexif12:armhf (0.6.21-5.1+deb10u5) ... Mar 14 08:18:08 volumio volumio[1322]: info: Setting up minidlna (1.2.1+dfsg-2+deb10u4) ... Mar 14 08:18:08 volumio groupadd[2922]: group added to /etc/group: name=minidlna, GID=123 Mar 14 08:18:08 volumio groupadd[2922]: group added to /etc/gshadow: name=minidlna Mar 14 08:18:08 volumio groupadd[2922]: new group: name=minidlna, GID=123 Mar 14 08:18:08 volumio useradd[2926]: new user: name=minidlna, UID=118, GID=123, home=/var/lib/minidlna, shell=/usr/sbin/nologin Mar 14 08:18:08 volumio usermod[2932]: change user 'minidlna' password Mar 14 08:18:08 volumio chage[2937]: changed password expiry for minidlna Mar 14 08:18:08 volumio chfn[2940]: changed user 'minidlna' information Mar 14 08:18:08 volumio systemd[1]: Reloading. Mar 14 08:18:08 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. Mar 14 08:18:08 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 14 08:18:08 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 14 08:18:08 volumio systemd[1]: Reloading. Mar 14 08:18:08 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. Mar 14 08:18:09 volumio systemd[1]: Reloading. Mar 14 08:18:09 volumio volumio[1322]: info: Created symlink /etc/systemd/system/multi-user.target.wants/minidlna.service → /lib/systemd/system/minidlna.service. Mar 14 08:18:09 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. Mar 14 08:18:09 volumio systemd[1]: Reloading. Mar 14 08:18:09 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. Mar 14 08:18:09 volumio systemd[1]: Starting MiniDLNA lightweight DLNA/UPnP-AV server... Mar 14 08:18:09 volumio volumio[1322]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 14 08:18:09 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Mar 14 08:18:09 volumio volumio[1322]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Mar 14 08:18:09 volumio volumio[1322]: info: Processing triggers for systemd (241-7~deb10u9+rpi1) ... Mar 14 08:18:09 volumio systemd[1]: Reloading. Mar 14 08:18:09 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. Mar 14 08:18:10 volumio systemd[1]: Reloading. Mar 14 08:18:10 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. Mar 14 08:18:10 volumio systemd[1]: Reloading. Mar 14 08:18:10 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. Mar 14 08:18:10 volumio systemd[1]: Reloading. Mar 14 08:18:10 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. Mar 14 08:18:10 volumio systemd[1]: Stopping MiniDLNA lightweight DLNA/UPnP-AV server... Mar 14 08:18:10 volumio volumio[1322]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 14 08:18:10 volumio systemd[1]: minidlna.service: Succeeded. Mar 14 08:18:10 volumio systemd[1]: Stopped MiniDLNA lightweight DLNA/UPnP-AV server. Mar 14 08:18:10 volumio volumio[1322]: info: Creating systemd unit /etc/systemd/system/minidlna.service Mar 14 08:18:10 volumio systemd[1]: Reloading. Mar 14 08:18:10 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. Mar 14 08:18:11 volumio volumio[1322]: info: Setting values for "network_interface" and "model_number" in /data/plugins/music_service/minidlna/config.json Mar 14 08:18:11 volumio volumio[1322]: info: Setting permissions to MiniDLNA folders Mar 14 08:18:11 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Mar 14 08:18:11 volumio volumio[1322]: info: Install script completed Mar 14 08:18:11 volumio volumio[1322]: info: Adding reference to registry Mar 14 08:18:11 volumio volumio[1322]: info: Done installing plugin. Mar 14 08:18:11 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 14 08:18:11 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 14 08:18:11 volumio volumio[1322]: Plugin install end detected on script Mar 14 08:18:11 volumio volumio[1322]: info: Folder /tmp/plugins removed Mar 14 08:18:11 volumio volumio[1322]: info: Folder /tmp/downloaded_plugin.zip removed Mar 14 08:18:11 volumio volumio[1322]: info: Folder /data/temp removed Mar 14 08:18:15 volumio volumio[1322]: info: Enabling plugin minidlna Mar 14 08:18:15 volumio volumio[1322]: info: Loading plugin "minidlna"... Mar 14 08:18:15 volumio volumio[1322]: info: PLUGIN START: minidlna Mar 14 08:18:15 volumio volumio[1322]: info: Loading i18n strings for locale fi Mar 14 08:18:15 volumio volumio[1322]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 14 08:18:15 volumio volumio[1322]: info: minidlna: Found miniDLNA version 1.2.1 Mar 14 08:18:15 volumio volumio[1322]: info: minidlna: /data/minidlna.conf written Mar 14 08:18:15 volumio volumio[1322]: info: minidlna: Starting minidlna.service Mar 14 08:18:15 volumio sudo[3139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 14 08:18:15 volumio sudo[3139]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:18:15 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Mar 14 08:18:15 volumio sudo[3139]: pam_unix(sudo:session): session closed for user root Mar 14 08:18:15 volumio volumio[1322]: info: minidlna: systemctl start minidlna.service succeeded. Mar 14 08:18:15 volumio volumio[1322]: info: Done. Mar 14 08:18:23 volumio volumio[1322]: info: Enabling plugin minidlna Mar 14 08:18:23 volumio volumio[1322]: info: Loading plugin "minidlna"... Mar 14 08:18:23 volumio volumio[1322]: info: Applying required configuration parameters for plugin minidlna Mar 14 08:18:23 volumio volumio[1322]: info: PLUGIN START: minidlna Mar 14 08:18:23 volumio volumio[1322]: info: Loading i18n strings for locale fi Mar 14 08:18:23 volumio volumio[1322]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 14 08:18:23 volumio volumio[1322]: info: minidlna: Found miniDLNA version 1.2.1 Mar 14 08:18:23 volumio volumio[1322]: info: minidlna: Starting minidlna.service Mar 14 08:18:23 volumio sudo[3225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 14 08:18:23 volumio sudo[3225]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:18:23 volumio sudo[3225]: pam_unix(sudo:session): session closed for user root Mar 14 08:18:23 volumio volumio[1322]: info: minidlna: systemctl start minidlna.service succeeded. Mar 14 08:18:23 volumio volumio[1322]: info: Done. Mar 14 08:18:50 volumio volumio[1322]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 14 08:18:52 volumio volumio[1322]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 14 08:18:52 volumio volumio[1322]: info: Folder /tmp/plugins removed Mar 14 08:18:52 volumio volumio[1322]: info: Check plugin dependencies Mar 14 08:18:52 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 14 08:18:52 volumio volumio[1322]: info: Checking if plugin already exists Mar 14 08:18:52 volumio volumio[1322]: info: Rename folder Mar 14 08:18:52 volumio volumio[1322]: info: Folder /tmp/downloaded_plugin.zip removed Mar 14 08:18:52 volumio volumio[1322]: info: Move to category Mar 14 08:18:53 volumio volumio[1322]: info: Checking if install.sh is present Mar 14 08:18:53 volumio volumio[1322]: info: Executing install.sh Mar 14 08:18:53 volumio sudo[3290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Mar 14 08:18:53 volumio sudo[3290]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:18:53 volumio volumio[1322]: info: Installing Go-librespot Mar 14 08:18:53 volumio volumio[1322]: info: Checking old vollibrespot installs Mar 14 08:18:53 volumio systemd[1]: Reloading. Mar 14 08:18:53 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. Mar 14 08:18:53 volumio volumio[1322]: info: Downloading daemon Mar 14 08:18:55 volumio volumio[1322]: info: Creating directories Mar 14 08:18:55 volumio volumio[1322]: info: Creating data directory Mar 14 08:18:55 volumio volumio[1322]: info: Creating Start Script Mar 14 08:18:55 volumio systemd[1]: Reloading. Mar 14 08:18:55 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. Mar 14 08:18:55 volumio sudo[3290]: pam_unix(sudo:session): session closed for user root Mar 14 08:18:55 volumio volumio[1322]: Plugin install end detected on script Mar 14 08:18:55 volumio volumio[1322]: info: Install script completed Mar 14 08:18:55 volumio volumio[1322]: info: Adding reference to registry Mar 14 08:18:55 volumio volumio[1322]: info: Done installing plugin. Mar 14 08:18:55 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 14 08:18:55 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 14 08:18:55 volumio volumio[1322]: info: Folder /tmp/plugins removed Mar 14 08:18:55 volumio volumio[1322]: info: Folder /tmp/downloaded_plugin.zip removed Mar 14 08:18:55 volumio volumio[1322]: info: Folder /data/temp removed Mar 14 08:18:57 volumio volumio[1322]: info: Enabling plugin spop Mar 14 08:18:57 volumio volumio[1322]: info: Loading plugin "spop"... Mar 14 08:18:57 volumio volumio[1322]: info: PLUGIN START: spop Mar 14 08:18:57 volumio volumio[1322]: info: Creating Spotify config file Mar 14 08:18:57 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 08:18:57 volumio volumio[1322]: info: Done. Mar 14 08:18:57 volumio volumio[1322]: info: Spotify config file written Mar 14 08:18:57 volumio sudo[3352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 14 08:18:57 volumio volumio[1322]: info: No need to fix Spotify hosts Mar 14 08:18:57 volumio sudo[3352]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:18:57 volumio systemd[1]: Started go-librespot Daemon. Mar 14 08:18:57 volumio go-librespot[3354]: go-librespot daemon starting... Mar 14 08:18:57 volumio sudo[3352]: pam_unix(sudo:session): session closed for user root Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=info msg="running go-librespot 0.2.0" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=debug msg="no app state found" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=debug msg="stored credentials not found" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=info msg="generated new device id: 452e1752a0a534a3cba964f3d697734507dffc58" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 14 08:18:57 volumio go-librespot[3354]: time="2025-03-14T08:18:57+02:00" level=info msg="zeroconf server listening on port 36939" Mar 14 08:19:00 volumio volumio[1322]: info: go-librespot daemon successfully initialized Mar 14 08:19:01 volumio volumio[1322]: info: Enabling plugin spop Mar 14 08:19:01 volumio volumio[1322]: info: Loading plugin "spop"... Mar 14 08:19:01 volumio volumio[1322]: info: PLUGIN START: spop Mar 14 08:19:01 volumio volumio[1322]: info: Creating Spotify config file Mar 14 08:19:01 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 14 08:19:01 volumio volumio[1322]: info: Done. Mar 14 08:19:01 volumio volumio[1322]: info: No need to fix Spotify hosts Mar 14 08:19:01 volumio volumio[1322]: info: Spotify config file written Mar 14 08:19:01 volumio sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 14 08:19:01 volumio sudo[3364]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 14 08:19:01 volumio systemd[1]: Stopping go-librespot Daemon... Mar 14 08:19:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 14 08:19:01 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Mar 14 08:19:01 volumio systemd[1]: Stopped go-librespot Daemon. Mar 14 08:19:01 volumio volumio[1322]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 14 08:19:01 volumio volumio[1322]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 14 08:19:01 volumio systemd[1]: Started go-librespot Daemon. Mar 14 08:19:01 volumio go-librespot[3366]: go-librespot daemon starting... Mar 14 08:19:01 volumio sudo[3364]: pam_unix(sudo:session): session closed for user root Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=info msg="running go-librespot 0.2.0" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=debug msg="app state loaded" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=debug msg="stored credentials not found" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 14 08:19:01 volumio go-librespot[3366]: time="2025-03-14T08:19:01+02:00" level=info msg="zeroconf server listening on port 39363" Mar 14 08:19:03 volumio volumio[1322]: info: Initializing connection to go-librespot Websocket Mar 14 08:19:03 volumio go-librespot[3366]: time="2025-03-14T08:19:03+02:00" level=debug msg="new websocket client" Mar 14 08:19:03 volumio volumio[1322]: info: Connection to go-librespot Websocket established Mar 14 08:19:04 volumio volumio[1322]: info: go-librespot daemon successfully initialized Mar 14 08:19:06 volumio volumio[1322]: info: Getting Spotify volume Mar 14 08:19:06 volumio volumio[1322]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 14 08:19:06 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 14 08:19:07 volumio volumio[1322]: info: Initializing connection to go-librespot Websocket Mar 14 08:19:07 volumio go-librespot[3366]: time="2025-03-14T08:19:07+02:00" level=debug msg="new websocket client" Mar 14 08:19:07 volumio volumio[1322]: info: Connection to go-librespot Websocket established Mar 14 08:19:10 volumio volumio[1322]: info: Getting Spotify volume Mar 14 08:19:10 volumio volumio[1322]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 14 08:19:10 volumio volumio[1322]: info: CoreCommandRouter::volumioGetState Mar 14 08:19:22 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 14 08:19:22 volumio volumio[1322]: info: CURURI: playlists Mar 14 08:19:22 volumio volumio[1322]: info: Listing playlists Mar 14 08:19:22 volumio volumio[1322]: info: Preload queue cleared Mar 14 08:19:33 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 14 08:19:33 volumio volumio[1322]: info: Preload queue cleared Mar 14 08:19:35 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 14 08:19:35 volumio volumio[1322]: info: Preload queue cleared Mar 14 08:19:40 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 14 08:19:40 volumio volumio[1322]: info: Preload queue cleared Mar 14 08:19:42 volumio volumio[1322]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 14 08:19:42 volumio volumio[1322]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 08:19:42 volumio volumio[1322]: TypeError: Cannot read property 'length' of undefined Mar 14 08:19:42 volumio volumio[1322]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 14 08:19:42 volumio volumio[1322]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 14 08:19:42 volumio volumio[1322]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 14 08:19:42 volumio volumio[1322]: at Parser.emit (events.js:315:20) Mar 14 08:19:42 volumio volumio[1322]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 14 08:19:42 volumio volumio[1322]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 14 08:19:42 volumio volumio[1322]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 14 08:19:42 volumio volumio[1322]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 14 08:19:42 volumio volumio[1322]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 14 08:19:42 volumio volumio[1322]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 14 08:19:42 volumio volumio[1322]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 14 08:19:42 volumio volumio[1322]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 14 08:19:42 volumio volumio[1322]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 14 08:19:42 volumio volumio[1322]: at IncomingMessage.emit (events.js:327:22) Mar 14 08:19:42 volumio volumio[1322]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 14 08:19:42 volumio volumio[1322]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 14 08:19:42 volumio volumio[1322]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 14 08:19:42 volumio sudo[3504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-14 08:18 Mar 14 08:19:42 volumio sudo[3504]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"