Dec 06 13:51:00 volumio volumio[1246]: info: Preparing to unpack .../27-libgs10_10.0.0~dfsg-11+deb12u8_armhf.deb ... Dec 06 13:51:00 volumio volumio[1246]: info: Unpacking libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Dec 06 13:51:03 volumio volumio[1246]: info: Selecting previously unselected package libxdamage1:armhf. Dec 06 13:51:03 volumio volumio[1246]: info: Preparing to unpack .../28-libxdamage1_1%3a1.1.6-1_armhf.deb ... Dec 06 13:51:03 volumio volumio[1246]: info: Unpacking libxdamage1:armhf (1:1.1.6-1) ... Dec 06 13:51:03 volumio volumio[1246]: info: Selecting previously unselected package libgtk-3-common. Dec 06 13:51:03 volumio volumio[1246]: info: Preparing to unpack .../29-libgtk-3-common_1%3a3.24.38-2~deb12u2+rpt7+rpi1_all.deb ... Dec 06 13:51:03 volumio volumio[1246]: info: Unpacking libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:04 volumio volumio[1246]: info: Selecting previously unselected package libgtk-3-0:armhf. Dec 06 13:51:04 volumio volumio[1246]: info: Preparing to unpack .../30-libgtk-3-0_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Dec 06 13:51:04 volumio volumio[1246]: info: Unpacking libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:06 volumio volumio[1246]: info: Selecting previously unselected package libgtk-3-bin. Dec 06 13:51:06 volumio volumio[1246]: info: Preparing to unpack .../31-libgtk-3-bin_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Dec 06 13:51:06 volumio volumio[1246]: info: Unpacking libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:06 volumio volumio[1246]: info: Selecting previously unselected package libspectre1:armhf. Dec 06 13:51:06 volumio volumio[1246]: info: Preparing to unpack .../32-libspectre1_0.2.12-1_armhf.deb ... Dec 06 13:51:06 volumio volumio[1246]: info: Unpacking libspectre1:armhf (0.2.12-1) ... Dec 06 13:51:07 volumio volumio[1246]: info: Selecting previously unselected package libimlib2:armhf. Dec 06 13:51:07 volumio volumio[1246]: info: Preparing to unpack .../33-libimlib2_1.10.0-4+deb12u1_armhf.deb ... Dec 06 13:51:07 volumio volumio[1246]: info: Unpacking libimlib2:armhf (1.10.0-4+deb12u1) ... Dec 06 13:51:08 volumio volumio[1246]: info: Selecting previously unselected package libobt2v5. Dec 06 13:51:08 volumio volumio[1246]: info: Preparing to unpack .../34-libobt2v5_3.6.1-10+rpt1_armhf.deb ... Dec 06 13:51:08 volumio volumio[1246]: info: Unpacking libobt2v5 (3.6.1-10+rpt1) ... Dec 06 13:51:08 volumio volumio[1246]: info: Selecting previously unselected package libpangoxft-1.0-0:armhf. Dec 06 13:51:08 volumio volumio[1246]: info: Preparing to unpack .../35-libpangoxft-1.0-0_1.50.12+ds-1_armhf.deb ... Dec 06 13:51:08 volumio volumio[1246]: info: Unpacking libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Dec 06 13:51:08 volumio volumio[1246]: info: Selecting previously unselected package libobrender32v5. Dec 06 13:51:08 volumio volumio[1246]: info: Preparing to unpack .../36-libobrender32v5_3.6.1-10+rpt1_armhf.deb ... Dec 06 13:51:08 volumio volumio[1246]: info: Unpacking libobrender32v5 (3.6.1-10+rpt1) ... Dec 06 13:51:09 volumio volumio[1246]: info: Selecting previously unselected package libpaper-utils. Dec 06 13:51:09 volumio volumio[1246]: info: Preparing to unpack .../37-libpaper-utils_1.1.29_armhf.deb ... Dec 06 13:51:09 volumio volumio[1246]: info: Unpacking libpaper-utils (1.1.29) ... Dec 06 13:51:09 volumio volumio[1246]: info: Selecting previously unselected package librsvg2-common:armhf. Dec 06 13:51:09 volumio volumio[1246]: info: Preparing to unpack .../38-librsvg2-common_2.54.7+dfsg-1~deb12u1_armhf.deb ... Dec 06 13:51:09 volumio volumio[1246]: info: Unpacking librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Dec 06 13:51:09 volumio volumio[1246]: info: Selecting previously unselected package libxcb-util1:armhf. Dec 06 13:51:09 volumio volumio[1246]: info: Preparing to unpack .../39-libxcb-util1_0.4.0-1_armhf.deb ... Dec 06 13:51:09 volumio volumio[1246]: info: Unpacking libxcb-util1:armhf (0.4.0-1) ... Dec 06 13:51:10 volumio volumio[1246]: info: Selecting previously unselected package libstartup-notification0:armhf. Dec 06 13:51:10 volumio volumio[1246]: info: Preparing to unpack .../40-libstartup-notification0_0.12-6+b1_armhf.deb ... Dec 06 13:51:10 volumio volumio[1246]: info: Unpacking libstartup-notification0:armhf (0.12-6+b1) ... Dec 06 13:51:10 volumio volumio[1246]: info: Selecting previously unselected package obconf. Dec 06 13:51:10 volumio volumio[1246]: info: Preparing to unpack .../41-obconf_1%3a2.0.4+git20150213-2+rpt4_armhf.deb ... Dec 06 13:51:10 volumio volumio[1246]: info: Unpacking obconf (1:2.0.4+git20150213-2+rpt4) ... Dec 06 13:51:11 volumio volumio[1246]: info: Selecting previously unselected package openbox. Dec 06 13:51:11 volumio volumio[1246]: info: Preparing to unpack .../42-openbox_3.6.1-10+rpt1_armhf.deb ... Dec 06 13:51:11 volumio volumio[1246]: info: Unpacking openbox (3.6.1-10+rpt1) ... Dec 06 13:51:13 volumio volumio[1246]: info: Selecting previously unselected package scrot. Dec 06 13:51:13 volumio volumio[1246]: info: Preparing to unpack .../43-scrot_1.8.1-1_armhf.deb ... Dec 06 13:51:13 volumio volumio[1246]: info: Unpacking scrot (1.8.1-1) ... Dec 06 13:51:14 volumio volumio[1246]: info: Setting up libpaper1:armhf (1.1.29) ... Dec 06 13:51:14 volumio volumio[1246]: info: Dec 06 13:51:14 volumio volumio[1246]: info: Creating config file /etc/papersize with new version Dec 06 13:51:15 volumio volumio[1246]: info: Setting up gtk-update-icon-cache (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:15 volumio volumio[1246]: info: Setting up libxdamage1:armhf (1:1.1.6-1) ... Dec 06 13:51:15 volumio volumio[1246]: info: Setting up libgdk-pixbuf-xlib-2.0-0:armhf (2.40.2-2) ... Dec 06 13:51:15 volumio volumio[1246]: info: Setting up fonts-noto-mono (20201225-1) ... Dec 06 13:51:15 volumio volumio[1246]: info: Setting up libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Dec 06 13:51:15 volumio volumio[1246]: info: Setting up hicolor-icon-theme (0.17-2) ... Dec 06 13:51:16 volumio volumio[1246]: info: Setting up libijs-0.35:armhf (0.35-15) ... Dec 06 13:51:16 volumio volumio[1246]: info: Setting up libgs-common (10.0.0~dfsg-11+deb12u8) ... Dec 06 13:51:16 volumio volumio[1246]: info: Setting up libpaper-utils (1.1.29) ... Dec 06 13:51:16 volumio volumio[1246]: info: Setting up libatspi2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Dec 06 13:51:16 volumio volumio[1246]: info: Setting up fonts-urw-base35 (20200910-7) ... Dec 06 13:51:17 volumio volumio[1246]: info: Setting up libcolord2:armhf (1.4.6-2.2) ... Dec 06 13:51:17 volumio volumio[1246]: info: Setting up libxcb-util1:armhf (0.4.0-1) ... Dec 06 13:51:17 volumio volumio[1246]: info: Setting up poppler-data (0.4.12-1) ... Dec 06 13:51:17 volumio volumio[1246]: info: Setting up dbus-user-session (1.14.10-1~deb12u1) ... Dec 06 13:51:17 volumio volumio[1246]: info: Setting up adwaita-icon-theme (43-1) ... Dec 06 13:51:18 volumio volumio[1246]: info: update-alternatives: using /usr/share/icons/Adwaita/cursor.theme to provide /usr/share/icons/default/index.theme (x-cursor-theme) in auto mode Dec 06 13:51:18 volumio volumio[1246]: info: Setting up libjbig2dec0:armhf (0.19-3+b2) ... Dec 06 13:51:18 volumio volumio[1246]: info: Setting up libgdk-pixbuf2.0-0:armhf (2.40.2-2) ... Dec 06 13:51:18 volumio volumio[1246]: info: Setting up libidn12:armhf (1.41-1+b1) ... Dec 06 13:51:18 volumio volumio[1246]: info: Setting up libobt2v5 (3.6.1-10+rpt1) ... Dec 06 13:51:18 volumio volumio[1246]: info: Setting up librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up libgif7:armhf (5.2.1-2.5) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up libgdk-pixbuf2.0-bin (2.42.10+dfsg-1+deb12u2) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up at-spi2-common (2.50.0-1~bpo12+rpt1) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up libatk1.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up fonts-droid-fallback (1:6.0.1r16-1.1) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up libstartup-notification0:armhf (0.12-6+b1) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up libgs10-common (10.0.0~dfsg-11+deb12u8) ... Dec 06 13:51:19 volumio volumio[1246]: info: Setting up dconf-service (0.40.0-4) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libatk-bridge2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up dconf-gsettings-backend:armhf (0.40.0-4) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libspectre1:armhf (0.2.12-1) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libimlib2:armhf (1.10.0-4+deb12u1) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libobrender32v5 (3.6.1-10+rpt1) ... Dec 06 13:51:20 volumio sudo[8841]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 13:51:20 volumio sudo[8841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:51:20 volumio sudo[8841]: pam_unix(sudo:session): session closed for user root Dec 06 13:51:20 volumio sudo[8843]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 13:51:20 volumio sudo[8843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:51:20 volumio sudo[8843]: pam_unix(sudo:session): session closed for user root Dec 06 13:51:20 volumio volumio[1246]: verbose: New Socket.io Connection to 192.168.0.145 from 192.168.0.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 06 13:51:20 volumio volumio[1246]: info: Setting up scrot (1.8.1-1) ... Dec 06 13:51:20 volumio volumio[1246]: info: Setting up libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:21 volumio volumio[1246]: info: Setting up gsettings-desktop-schemas (43.0-1+rpt1) ... Dec 06 13:51:21 volumio volumio[1246]: info: Setting up openbox (3.6.1-10+rpt1) ... Dec 06 13:51:21 volumio sudo[8847]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 13:51:21 volumio sudo[8847]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:51:21 volumio sudo[8849]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 13:51:21 volumio sudo[8849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:51:21 volumio sudo[8847]: pam_unix(sudo:session): session closed for user root Dec 06 13:51:21 volumio sudo[8849]: pam_unix(sudo:session): session closed for user root Dec 06 13:51:21 volumio volumio[1246]: verbose: New Socket.io Connection to 192.168.0.145 from 192.168.0.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 13:51:21 volumio volumio[1246]: info: update-alternatives: using /usr/bin/openbox to provide /usr/bin/x-window-manager (x-window-manager) in auto mode Dec 06 13:51:21 volumio volumio[1246]: info: update-alternatives: using /usr/bin/openbox-session to provide /usr/bin/x-session-manager (x-session-manager) in auto mode Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:21 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 13:51:21 volumio volumio[1246]: info: Received Get System Info Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:51:21 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:21 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:21 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:21 volumio volumio[1246]: info: Listing playlists Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 13:51:21 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 13:51:21 volumio volumio[1246]: info: Processing triggers for libglib2.0-0:armhf (2.74.6-2+deb12u7) ... Dec 06 13:51:21 volumio volumio[1246]: info: Setting up libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:22 volumio volumio[1246]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 06 13:51:22 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:22 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:22 volumio volumio[1246]: info: Setting up libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Dec 06 13:51:22 volumio volumio[1246]: info: Processing triggers for man-db (2.11.2-2) ... Dec 06 13:51:22 volumio volumio[1246]: info: Processing triggers for shared-mime-info (2.2-1) ... Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 13:51:24 volumio volumio[1246]: info: Received Get System Info Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:51:24 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:24 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 13:51:24 volumio volumio[1246]: info: Received Get System Info Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:51:24 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:24 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:24 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:51:32 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 06 13:51:40 volumio volumio[1246]: info: Setting up at-spi2-core (2.50.0-1~bpo12+rpt1) ... Dec 06 13:51:40 volumio volumio[1246]: info: Processing triggers for libgdk-pixbuf-2.0-0:armhf (2.42.10+dfsg-1+deb12u2) ... Dec 06 13:51:40 volumio volumio[1246]: info: Processing triggers for mailcap (3.70+nmu1) ... Dec 06 13:51:40 volumio volumio[1246]: info: Processing triggers for fontconfig (2.14.1-4) ... Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 13:51:41 volumio volumio[1246]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 06 13:51:41 volumio volumio[1246]: info: Received Get System Version Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 13:51:41 volumio volumio[1246]: info: Received Get System Info Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:51:41 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:51:41 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:51:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:51:41 volumio volumio[1246]: info: Setting up obconf (1:2.0.4+git20150213-2+rpt4) ... Dec 06 13:51:42 volumio volumio[1246]: info: Creating /etc/X11/xorg.conf.d dir Dec 06 13:51:42 volumio volumio[1246]: info: Creating Xorg configuration Dec 06 13:51:42 volumio volumio[1246]: info: Installing Chromium Dec 06 13:51:43 volumio volumio[1246]: info: Reading package lists... Dec 06 13:51:43 volumio volumio[1246]: info: Building dependency tree... Dec 06 13:51:43 volumio volumio[1246]: info: Reading state information... Dec 06 13:51:44 volumio volumio[1246]: info: The following additional packages will be installed: Dec 06 13:51:44 volumio volumio[1246]: info: chromium chromium-codecs-ffmpeg-extra chromium-common chromium-l10n Dec 06 13:51:44 volumio volumio[1246]: info: chromium-sandbox fonts-liberation libdouble-conversion3 libfile-basedir-perl Dec 06 13:51:44 volumio volumio[1246]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libharfbuzz-subset0 Dec 06 13:51:44 volumio volumio[1246]: info: libio-stringy-perl libipc-system-simple-perl libnet-dbus-perl libnspr4 Dec 06 13:51:44 volumio volumio[1246]: info: libnss3 libopenh264-7 libtie-ixhash-perl libupower-glib3 libwidevinecdm0 Dec 06 13:51:44 volumio volumio[1246]: info: libx11-protocol-perl libxml-parser-perl libxml-twig-perl Dec 06 13:51:44 volumio volumio[1246]: info: libxml-xpathengine-perl libxnvctrl0 libxslt1.1 notification-daemon Dec 06 13:51:44 volumio volumio[1246]: info: rpi-chromium-mods upower xdg-utils zenoty Dec 06 13:51:44 volumio volumio[1246]: info: Suggested packages: Dec 06 13:51:44 volumio volumio[1246]: info: chromium-shell chromium-driver libunicode-map8-perl libunicode-string-perl Dec 06 13:51:44 volumio volumio[1246]: info: xml-twig-tools Dec 06 13:51:44 volumio volumio[1246]: info: The following NEW packages will be installed: Dec 06 13:51:44 volumio volumio[1246]: info: chromium chromium-browser chromium-codecs-ffmpeg-extra chromium-common Dec 06 13:51:44 volumio volumio[1246]: info: chromium-l10n chromium-sandbox fonts-liberation libdouble-conversion3 Dec 06 13:51:44 volumio volumio[1246]: info: libfile-basedir-perl libfile-desktopentry-perl libfile-mimeinfo-perl Dec 06 13:51:44 volumio volumio[1246]: info: libharfbuzz-subset0 libio-stringy-perl libipc-system-simple-perl Dec 06 13:51:44 volumio volumio[1246]: info: libnet-dbus-perl libnspr4 libnss3 libopenh264-7 libtie-ixhash-perl Dec 06 13:51:44 volumio volumio[1246]: info: libupower-glib3 libwidevinecdm0 libx11-protocol-perl libxml-parser-perl Dec 06 13:51:44 volumio volumio[1246]: info: libxml-twig-perl libxml-xpathengine-perl libxnvctrl0 libxslt1.1 Dec 06 13:51:44 volumio volumio[1246]: info: notification-daemon rpi-chromium-mods upower xdg-utils zenoty Dec 06 13:51:45 volumio volumio[1246]: info: 0 upgraded, 32 newly installed, 0 to remove and 0 not upgraded. Dec 06 13:51:45 volumio volumio[1246]: info: Need to get 155 MB of archives. Dec 06 13:51:45 volumio volumio[1246]: info: After this operation, 380 MB of additional disk space will be used. Dec 06 13:51:45 volumio volumio[1246]: info: Get:2 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-codecs-ffmpeg-extra armhf 126.0.6478.164-rpt1 [963 kB] Dec 06 13:51:45 volumio volumio[1246]: info: Get:1 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libdouble-conversion3 armhf 3.2.1-1 [38.8 kB] Dec 06 13:51:45 volumio volumio[1246]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libharfbuzz-subset0 armhf 6.0.0+dfsg-3 [1881 kB] Dec 06 13:51:45 volumio volumio[1246]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf zenoty armhf 0.2 [7194 B] Dec 06 13:51:45 volumio volumio[1246]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-common armhf 1:142.0.7444.175-1~deb12u1+rpt1 [27.9 MB] Dec 06 13:51:46 volumio volumio[1246]: info: Get:6 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libnspr4 armhf 2:4.35-1 [90.9 kB] Dec 06 13:51:46 volumio volumio[1246]: info: Get:9 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxnvctrl0 armhf 525.85.05-3~deb12u1 [12.3 kB] Dec 06 13:51:46 volumio volumio[1246]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libnss3 armhf 2:3.87.1-1+deb12u1 [1089 kB] Dec 06 13:51:46 volumio volumio[1246]: info: Get:11 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf xdg-utils all 1.1.3-4.1 [75.5 kB] Dec 06 13:51:46 volumio volumio[1246]: info: Get:12 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf fonts-liberation all 1:1.07.4-11 [828 kB] Dec 06 13:51:47 volumio volumio[1246]: info: Get:13 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libipc-system-simple-perl all 1.30-2 [26.8 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:14 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-basedir-perl all 0.09-2 [15.1 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:15 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-desktopentry-perl all 0.22-3 [17.6 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:16 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfile-mimeinfo-perl all 0.33-1 [48.3 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:17 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libio-stringy-perl all 2.111-3 [56.5 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libopenh264-7 armhf 2.3.1+dfsg-3+deb12u2 [315 kB] Dec 06 13:51:48 volumio volumio[1246]: info: Get:18 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxml-parser-perl armhf 2.46-4 [197 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:19 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxml-twig-perl all 1:3.52-2 [181 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:20 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libnet-dbus-perl armhf 1.2.0-2 [174 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:21 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libtie-ixhash-perl all 1.23-4 [11.7 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:22 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libupower-glib3 armhf 0.99.20-2 [29.8 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:23 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libx11-protocol-perl all 0.56-9 [120 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:10 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxslt1.1 armhf 1.1.35-1+deb12u3 [209 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:24 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libxml-xpathengine-perl all 0.14-2 [33.5 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:25 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf notification-daemon armhf 3.20.0-4 [56.3 kB] Dec 06 13:51:49 volumio volumio[1246]: info: Get:26 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf upower armhf 0.99.20-2 [75.6 kB] Dec 06 13:51:52 volumio volumio[1246]: info: Get:27 http://archive.raspberrypi.com/debian bookworm/main armhf chromium armhf 1:142.0.7444.175-1~deb12u1+rpt1 [93.8 MB] Dec 06 13:52:07 volumio volumio[1246]: info: Get:28 http://archive.raspberrypi.com/debian bookworm/main armhf rpi-chromium-mods armhf 20250506 [5078 B] Dec 06 13:52:07 volumio volumio[1246]: info: Get:29 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-browser all 1:142.0.7444.175-1~deb12u1+rpt1 [108 kB] Dec 06 13:52:07 volumio volumio[1246]: info: Get:30 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-l10n all 1:142.0.7444.175-1~deb12u1+rpt1 [19.4 MB] Dec 06 13:52:10 volumio volumio[1246]: info: Get:31 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-sandbox armhf 1:142.0.7444.175-1~deb12u1+rpt1 [114 kB] Dec 06 13:52:10 volumio volumio[1246]: info: Get:32 http://archive.raspberrypi.com/debian bookworm/main armhf libwidevinecdm0 armhf 4.10.2662.3+1 [7466 kB] Dec 06 13:52:12 volumio volumio[1246]: info: Fetched 155 MB in 27s (5819 kB/s) Dec 06 13:52:12 volumio volumio[1246]: info: Selecting previously unselected package chromium-codecs-ffmpeg-extra. Dec 06 13:52:12 volumio volumio[1246]: [620B blob data] Dec 06 13:52:12 volumio volumio[1246]: info: Preparing to unpack .../00-chromium-codecs-ffmpeg-extra_126.0.6478.164-rpt1_armhf.deb ... Dec 06 13:52:12 volumio volumio[1246]: info: Unpacking chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Dec 06 13:52:13 volumio volumio[1246]: info: Selecting previously unselected package libdouble-conversion3:armhf. Dec 06 13:52:13 volumio volumio[1246]: info: Preparing to unpack .../01-libdouble-conversion3_3.2.1-1_armhf.deb ... Dec 06 13:52:13 volumio volumio[1246]: info: Unpacking libdouble-conversion3:armhf (3.2.1-1) ... Dec 06 13:52:13 volumio volumio[1246]: info: Selecting previously unselected package libharfbuzz-subset0:armhf. Dec 06 13:52:13 volumio volumio[1246]: info: Preparing to unpack .../02-libharfbuzz-subset0_6.0.0+dfsg-3_armhf.deb ... Dec 06 13:52:13 volumio volumio[1246]: info: Unpacking libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Dec 06 13:52:13 volumio volumio[1246]: info: Selecting previously unselected package libnspr4:armhf. Dec 06 13:52:13 volumio volumio[1246]: info: Preparing to unpack .../03-libnspr4_2%3a4.35-1_armhf.deb ... Dec 06 13:52:13 volumio volumio[1246]: info: Unpacking libnspr4:armhf (2:4.35-1) ... Dec 06 13:52:14 volumio volumio[1246]: info: Selecting previously unselected package libnss3:armhf. Dec 06 13:52:14 volumio volumio[1246]: info: Preparing to unpack .../04-libnss3_2%3a3.87.1-1+deb12u1_armhf.deb ... Dec 06 13:52:14 volumio volumio[1246]: info: Unpacking libnss3:armhf (2:3.87.1-1+deb12u1) ... Dec 06 13:52:14 volumio volumio[1246]: info: Selecting previously unselected package libopenh264-7:armhf. Dec 06 13:52:14 volumio volumio[1246]: info: Preparing to unpack .../05-libopenh264-7_2.3.1+dfsg-3+deb12u2_armhf.deb ... Dec 06 13:52:14 volumio volumio[1246]: info: Unpacking libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Dec 06 13:52:15 volumio volumio[1246]: info: Selecting previously unselected package libxnvctrl0:armhf. Dec 06 13:52:15 volumio volumio[1246]: info: Preparing to unpack .../06-libxnvctrl0_525.85.05-3~deb12u1_armhf.deb ... Dec 06 13:52:15 volumio volumio[1246]: info: Unpacking libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Dec 06 13:52:15 volumio volumio[1246]: info: Selecting previously unselected package libxslt1.1:armhf. Dec 06 13:52:15 volumio volumio[1246]: info: Preparing to unpack .../07-libxslt1.1_1.1.35-1+deb12u3_armhf.deb ... Dec 06 13:52:15 volumio volumio[1246]: info: Unpacking libxslt1.1:armhf (1.1.35-1+deb12u3) ... Dec 06 13:52:15 volumio volumio[1246]: info: Selecting previously unselected package xdg-utils. Dec 06 13:52:15 volumio volumio[1246]: info: Preparing to unpack .../08-xdg-utils_1.1.3-4.1_all.deb ... Dec 06 13:52:15 volumio volumio[1246]: info: Unpacking xdg-utils (1.1.3-4.1) ... Dec 06 13:52:16 volumio volumio[1246]: info: Selecting previously unselected package zenoty. Dec 06 13:52:16 volumio volumio[1246]: info: Preparing to unpack .../09-zenoty_0.2_armhf.deb ... Dec 06 13:52:16 volumio volumio[1246]: info: Unpacking zenoty (0.2) ... Dec 06 13:52:16 volumio volumio[1246]: info: Selecting previously unselected package chromium-common. Dec 06 13:52:16 volumio volumio[1246]: info: Preparing to unpack .../10-chromium-common_1%3a142.0.7444.175-1~deb12u1+rpt1_armhf.deb ... Dec 06 13:52:16 volumio volumio[1246]: info: Unpacking chromium-common (1:142.0.7444.175-1~deb12u1+rpt1) ... Dec 06 13:52:20 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 13:52:20 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 13:52:23 volumio volumio[1246]: info: Selecting previously unselected package chromium. Dec 06 13:52:23 volumio volumio[1246]: info: Preparing to unpack .../11-chromium_1%3a142.0.7444.175-1~deb12u1+rpt1_armhf.deb ... Dec 06 13:52:23 volumio volumio[1246]: info: Unpacking chromium (1:142.0.7444.175-1~deb12u1+rpt1) ... Dec 06 13:52:25 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 13:52:34 volumio volumio[1246]: info: Received OAUTH Data Dec 06 13:52:34 volumio volumio[1246]: info: Executing Spotify Oauth Login Dec 06 13:52:34 volumio volumio[1246]: info: Saving Spotify Refresh Token Dec 06 13:52:34 volumio volumio[1246]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 13:52:34 volumio volumio[1246]: SPOTIFY: BQBOTYdcIcmNWntQdQTgyy0kS9nYP-EWbYA5UOFH7CHRtlwcCmAakmRd_EFFnG3BMyYhqEGv02_GawLhgQ-qjSJuu1yYf0iZ7V-aUSEKvUCpKl3GCW13S8MVqIoFyyaTVmAvv-Tc3pBs2o7QxL9FM-U4fw5PdIrwJY4SJrUsxjSO_HD0GSVLAFmTVexLMVoKF9XPwNjn0LjwfI7iKEfs_dg6T4OdIwviTrrqoW57HzqxaXL7M71IAGFsdGVGmuEnkl3Klp0TbCq5Ai2vl5pKVpaxVEGUtuQPnIc6ObdYJ_Y1YOM7uRTD8aAW Dec 06 13:52:34 volumio volumio[1246]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 13:52:34 volumio volumio[1246]: info: New Spotify access token = BQBOTYdcIcmNWntQdQTgyy0kS9nYP-EWbYA5UOFH7CHRtlwcCmAakmRd_EFFnG3BMyYhqEGv02_GawLhgQ-qjSJuu1yYf0iZ7V-aUSEKvUCpKl3GCW13S8MVqIoFyyaTVmAvv-Tc3pBs2o7QxL9FM-U4fw5PdIrwJY4SJrUsxjSO_HD0GSVLAFmTVexLMVoKF9XPwNjn0LjwfI7iKEfs_dg6T4OdIwviTrrqoW57HzqxaXL7M71IAGFsdGVGmuEnkl3Klp0TbCq5Ai2vl5pKVpaxVEGUtuQPnIc6ObdYJ_Y1YOM7uRTD8aAW Dec 06 13:52:34 volumio volumio[1246]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 06 13:52:34 volumio volumio[1246]: SPOTIFY: User informations: {"country":"PL","display_name":"Artur","email":"artur.nadolski@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/pvfg5jmt9t7mhg2sfuf51dty4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/pvfg5jmt9t7mhg2sfuf51dty4","id":"pvfg5jmt9t7mhg2sfuf51dty4","images":[],"product":"premium","type":"user","uri":"spotify:user:pvfg5jmt9t7mhg2sfuf51dty4"} Dec 06 13:52:34 volumio volumio[1246]: info: Creating Spotify config file Dec 06 13:52:34 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 13:52:35 volumio volumio[1246]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 13:52:35 volumio volumio[1246]: SPOTIFY: BQCNDFpXNZ9NHamjqwKxMLwbidAG53RLnooR6eou2UQRhetSWVXQlogqgSe5wQ-T8ek6TjnhITm_w4gw2uDmzdOViTt1hg5UV2W71x8HJoY7cYj5Yeu84j8zfOMx0gOigGdKiMhjmGM_xXHOSCFDtOFOgyAeTEQUMrJFQ4LP4m7oT-0-9sOHNxj6p56I6wMsT5zjoXwruhGPCMtrzWRJTe2nDCeR5ZAQT0i0mJBGrWCD1L_CZjlBixdvjctmSUjnS5NwyL2d1LOPpC4Cwui_1w7bXBzhHgdzCEyAorDHoTi57PTlIzC74MV_ Dec 06 13:52:35 volumio volumio[1246]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 06 13:52:35 volumio volumio[1246]: info: New Spotify access token = BQCNDFpXNZ9NHamjqwKxMLwbidAG53RLnooR6eou2UQRhetSWVXQlogqgSe5wQ-T8ek6TjnhITm_w4gw2uDmzdOViTt1hg5UV2W71x8HJoY7cYj5Yeu84j8zfOMx0gOigGdKiMhjmGM_xXHOSCFDtOFOgyAeTEQUMrJFQ4LP4m7oT-0-9sOHNxj6p56I6wMsT5zjoXwruhGPCMtrzWRJTe2nDCeR5ZAQT0i0mJBGrWCD1L_CZjlBixdvjctmSUjnS5NwyL2d1LOPpC4Cwui_1w7bXBzhHgdzCEyAorDHoTi57PTlIzC74MV_ Dec 06 13:52:35 volumio volumio[1246]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 06 13:52:35 volumio volumio[1246]: SPOTIFY: User informations: {"country":"PL","display_name":"Artur","email":"artur.nadolski@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/pvfg5jmt9t7mhg2sfuf51dty4"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/pvfg5jmt9t7mhg2sfuf51dty4","id":"pvfg5jmt9t7mhg2sfuf51dty4","images":[],"product":"premium","type":"user","uri":"spotify:user:pvfg5jmt9t7mhg2sfuf51dty4"} Dec 06 13:52:35 volumio volumio[1246]: info: Spotify Successfully logged in Dec 06 13:52:35 volumio volumio[1246]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 06 13:52:35 volumio volumio[1246]: info: [1765025555241] CoreMusicLibrary::Adding element Spotify Dec 06 13:52:35 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 13:52:35 volumio volumio[1246]: Cannot find translation for source RADIO 357 Dec 06 13:52:35 volumio volumio[1246]: Cannot find translation for source Radio Paradise Dec 06 13:52:35 volumio volumio[1246]: Cannot find translation for source Spotify Dec 06 13:52:36 volumio volumio[1246]: info: Spotify config file written Dec 06 13:52:40 volumio sudo[9176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 06 13:52:40 volumio sudo[9178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 06 13:52:40 volumio sudo[9176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:52:40 volumio sudo[9178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:52:40 volumio sudo[9174]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 06 13:52:40 volumio sudo[9174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 13:52:40 volumio sudo[9176]: pam_unix(sudo:session): session closed for user root Dec 06 13:52:40 volumio sudo[9174]: pam_unix(sudo:session): session closed for user root Dec 06 13:52:40 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 06 13:52:40 volumio systemd[1]: go-librespot-daemon.service: Killing process 3214 (go-librespot) with signal SIGKILL. Dec 06 13:52:40 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 06 13:52:40 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:40 volumio volumio[1246]: info: Connection to go-librespot Websocket closed Dec 06 13:52:40 volumio volumio[1246]: verbose: New Socket.io Connection to 192.168.0.145 from 192.168.0.247 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 06 13:52:40 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:40 volumio go-librespot[9187]: go-librespot daemon starting... Dec 06 13:52:40 volumio sudo[9178]: pam_unix(sudo:session): session closed for user root Dec 06 13:52:40 volumio go-librespot[9188]: time="2025-12-06T13:52:40+01:00" level=info msg="running go-librespot 0.4.0" Dec 06 13:52:40 volumio go-librespot[9188]: time="2025-12-06T13:52:40+01:00" level=debug msg="app state loaded" Dec 06 13:52:40 volumio go-librespot[9188]: time="2025-12-06T13:52:40+01:00" level=debug msg="stored credentials not found" Dec 06 13:52:40 volumio go-librespot[9188]: time="2025-12-06T13:52:40+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01: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-gew1.spotify.com:80]" Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=info msg="zeroconf server listening on port 46375" Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=debug msg="obtained new client token: AAA6PIzTApgdXuf7V+eqkTsycE0wfgOQRa72qcf4IZrWcS7E4wY7iopCf4ck7MyRxwZz4USRfzBJMDC/kSoZM19ziYKvoH/f4eM22WdzTQzrkpLGXOtadXd6CLr0iuyrzcdIfBGhivqO+Q+RRtIdc9VF4SsS4f46hQJcK9/3f51L26olP156+8c+9MktI3tnEUav5mzxkE35hsloLyujlNOtFr6C4hUr5TJbPJ3/ejxvbySgcbn8iZFK6w==" Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetVisibleSources Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:52:41 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 06 13:52:41 volumio volumio[1246]: info: Received Get System Info Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:52:41 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:52:41 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:52:41 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:52:41 volumio volumio[1246]: info: Listing playlists Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 06 13:52:41 volumio go-librespot[9188]: time="2025-12-06T13:52:41+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.0.145:55636->34.158.1.133:4070: read: connection reset by peer" Dec 06 13:52:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 13:52:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 13:52:41 volumio volumio[1246]: info: Received Get System Info Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:52:41 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:52:41 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:52:41 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:52:41 volumio volumio[1246]: info: Selecting previously unselected package rpi-chromium-mods. Dec 06 13:52:41 volumio volumio[1246]: info: Preparing to unpack .../12-rpi-chromium-mods_20250506_armhf.deb ... Dec 06 13:52:41 volumio volumio[1246]: info: Unpacking rpi-chromium-mods (20250506) ... Dec 06 13:52:41 volumio volumio[1246]: info: Selecting previously unselected package chromium-browser. Dec 06 13:52:41 volumio volumio[1246]: info: Preparing to unpack .../13-chromium-browser_1%3a142.0.7444.175-1~deb12u1+rpt1_all.deb ... Dec 06 13:52:41 volumio volumio[1246]: info: Unpacking chromium-browser (1:142.0.7444.175-1~deb12u1+rpt1) ... Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 13:52:42 volumio volumio[1246]: info: Received Get System Info Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 06 13:52:42 volumio volumio[1246]: info: Discovery: Getting this device information Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 06 13:52:42 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 06 13:52:42 volumio volumio[1246]: info: Selecting previously unselected package chromium-l10n. Dec 06 13:52:42 volumio volumio[1246]: info: Preparing to unpack .../14-chromium-l10n_1%3a142.0.7444.175-1~deb12u1+rpt1_all.deb ... Dec 06 13:52:42 volumio volumio[1246]: info: Unpacking chromium-l10n (1:142.0.7444.175-1~deb12u1+rpt1) ... Dec 06 13:52:42 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 06 13:52:43 volumio volumio[1246]: info: Initializing connection to go-librespot Websocket Dec 06 13:52:43 volumio volumio[1246]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 13:52:43 volumio volumio[1246]: info: go-librespot daemon successfully initialized Dec 06 13:52:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 06 13:52:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:44 volumio go-librespot[9225]: go-librespot daemon starting... Dec 06 13:52:44 volumio go-librespot[9226]: time="2025-12-06T13:52:44+01:00" level=info msg="running go-librespot 0.4.0" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01:00" level=debug msg="app state loaded" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01:00" level=debug msg="stored credentials not found" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01: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]" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01: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]" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01: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]" Dec 06 13:52:45 volumio go-librespot[9226]: time="2025-12-06T13:52:45+01:00" level=info msg="zeroconf server listening on port 34653" Dec 06 13:52:46 volumio volumio[1246]: info: Initializing connection to go-librespot Websocket Dec 06 13:52:46 volumio volumio[1246]: info: Initializing connection to go-librespot Websocket Dec 06 13:52:48 volumio go-librespot[9226]: time="2025-12-06T13:52:48+01:00" level=debug msg="new websocket client" Dec 06 13:52:48 volumio go-librespot[9226]: time="2025-12-06T13:52:48+01:00" level=debug msg="new websocket client" Dec 06 13:52:48 volumio volumio[1246]: info: Connection to go-librespot Websocket established Dec 06 13:52:48 volumio volumio[1246]: info: Connection to go-librespot Websocket established Dec 06 13:52:48 volumio go-librespot[9226]: time="2025-12-06T13:52:48+01:00" level=debug msg="obtained new client token: AABubzbYtM8BuKREmF7/Y7aqXvChMAsiYyKiQxj2QXBQpns0zr6Q/lZo0fpGEY8+OwfnanRPVvZJekZG15XfUnCeeEXztDyNiT1AjI+V7nLKzSJS4oEfxbVXo4fCEBXZbuFsgNpF83Wklo6sslMCHhzbhozs4TUQDnXtFMTbZJt1eayifZ4BFz3zrLVxkqa3Ofo6/szu42Vs0MmAfQ1keRB1STmd1KLKGKuj+EleIw/S2cK+IDbXYFA5kphW" Dec 06 13:52:48 volumio go-librespot[9226]: time="2025-12-06T13:52:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 06 13:52:49 volumio go-librespot[9226]: time="2025-12-06T13:52:49+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Dec 06 13:52:49 volumio volumio[1246]: info: Connection to go-librespot Websocket closed Dec 06 13:52:49 volumio volumio[1246]: info: Connection to go-librespot Websocket closed Dec 06 13:52:49 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 06 13:52:49 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 06 13:52:50 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 06 13:52:51 volumio volumio[1246]: info: Getting Spotify volume Dec 06 13:52:51 volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 13:52:51 volumio volumio[1246]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 06 13:52:51 volumio volumio[1246]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 06 13:52:51 volumio volumio[1246]: errno: -111, Dec 06 13:52:51 volumio volumio[1246]: code: 'ECONNREFUSED', Dec 06 13:52:51 volumio volumio[1246]: syscall: 'connect', Dec 06 13:52:51 volumio volumio[1246]: address: '127.0.0.1', Dec 06 13:52:51 volumio volumio[1246]: port: 9879, Dec 06 13:52:51 volumio volumio[1246]: response: undefined Dec 06 13:52:51 volumio volumio[1246]: } Dec 06 13:52:51 volumio volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 13:52:52 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 06 13:52:52 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:52 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 06 13:52:52 volumio go-librespot[9261]: go-librespot daemon starting... Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=info msg="running go-librespot 0.4.0" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="app state loaded" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="stored credentials not found" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=info msg="zeroconf server listening on port 40663" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="obtained new client token: AAABNmZk41ssx2d9/XF9rg6up4qOlXXA4ZN15loOuPI/17tV57YSUhpFVfSrcZ7DMMbtQChvL1dHCfJcf/AHGutKOHp4oxPj7jNclm5xoHFGMBQy2vMBC3KRntse1YEt21T0e29RP+UUiJInTjpSW07cYwerPFesmPWSQs0nrzjsKJTXJvlz3Q06TH0D4AgFx5BjFIuNQb7/PMKeTPG2vB9Sec7t5O2eUrKUM96N1YHEkZvkV78ouC/uJ2Ul" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="completed keyexchange" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=debug msg="completed challenge" Dec 06 13:52:52 volumio go-librespot[9262]: time="2025-12-06T13:52:52+01:00" level=info msg="authenticated AP" username="pv*********************y4" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=info msg="authenticated Login5" username="pv*********************y4" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="stored credentials" username="pv*********************y4" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="initializing zeroconf session" username="pv*********************y4" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="dealer connection opened" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=trace msg="starting accesspoint recv loop" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=trace msg="starting dealer recv loop" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=trace msg="received accesspoint ping" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=trace msg="received accesspoint pong ack" Dec 06 13:52:53 volumio go-librespot[9262]: time="2025-12-06T13:52:53+01:00" level=debug msg="received connection id: YzljZWVhN2QtNGRi...ODA1QUQ4MUI2MQ==" Dec 06 13:52:54 volumio go-librespot[9262]: time="2025-12-06T13:52:54+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 06 13:52:56 volumio sudo[9288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 13:51' Dec 06 13:52:56 volumio sudo[9288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"