Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 19:04:04 volumiox-mark volumio[1332]: info: Received Get System Version Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 19:04:04 volumiox-mark volumio[1332]: info: Received Get System Info Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 19:04:04 volumiox-mark volumio[1332]: info: Discovery: Getting this device information Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:04 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 19:04:13 volumiox-mark volumio[1332]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jan 11 19:04:15 volumiox-mark volumio[1332]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Jan 11 19:04:15 volumiox-mark volumio[1332]: info: Folder /tmp/plugins removed Jan 11 19:04:16 volumiox-mark volumio[1332]: info: Check plugin dependencies Jan 11 19:04:16 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 19:04:16 volumiox-mark volumio[1332]: info: Checking if plugin already exists Jan 11 19:04:16 volumiox-mark volumio[1332]: info: Rename folder Jan 11 19:04:16 volumiox-mark volumio[1332]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 19:04:16 volumiox-mark volumio[1332]: info: Move to category Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Checking if install.sh is present Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Executing install.sh Jan 11 19:04:20 volumiox-mark sudo[5830]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Jan 11 19:04:20 volumiox-mark sudo[5830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Installing Go-librespot Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Installing Go-librespot Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Checking old vollibrespot installs Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Checking old vollibrespot installs Jan 11 19:04:20 volumiox-mark systemd[1]: Reloading. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Jan 11 19:04:20 volumiox-mark volumio[1332]: Selecting previously unselected package at-spi2-common. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Jan 11 19:04:20 volumiox-mark volumio[1332]: Selecting previously unselected package at-spi2-common. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../05-at-spi2-common_2.50.0-1~bpo12+rpt1_all.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking at-spi2-common (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../05-at-spi2-common_2.50.0-1~bpo12+rpt1_all.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking at-spi2-common (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatspi2.0-0:armhf. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatspi2.0-0:armhf. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../06-libatspi2.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../06-libatspi2.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking libatspi2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking libatspi2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package dbus-user-session. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package dbus-user-session. Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../07-dbus-user-session_1.14.10-1~deb12u1_armhf.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking dbus-user-session (1.14.10-1~deb12u1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../07-dbus-user-session_1.14.10-1~deb12u1_armhf.deb ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Unpacking dbus-user-session (1.14.10-1~deb12u1) ... Jan 11 19:04:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package dconf-service. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../08-dconf-service_0.40.0-4_armhf.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Selecting previously unselected package dconf-service. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../08-dconf-service_0.40.0-4_armhf.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking dconf-service (0.40.0-4) ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking dconf-service (0.40.0-4) ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Selecting previously unselected package dconf-gsettings-backend:armhf. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../09-dconf-gsettings-backend_0.40.0-4_armhf.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Selecting previously unselected package dconf-gsettings-backend:armhf. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../09-dconf-gsettings-backend_0.40.0-4_armhf.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking dconf-gsettings-backend:armhf (0.40.0-4) ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking dconf-gsettings-backend:armhf (0.40.0-4) ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Selecting previously unselected package gsettings-desktop-schemas. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Selecting previously unselected package gsettings-desktop-schemas. Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../10-gsettings-desktop-schemas_43.0-1+rpt1_all.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking gsettings-desktop-schemas (43.0-1+rpt1) ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Preparing to unpack .../10-gsettings-desktop-schemas_43.0-1+rpt1_all.deb ... Jan 11 19:04:21 volumiox-mark volumio[1332]: info: Unpacking gsettings-desktop-schemas (43.0-1+rpt1) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package at-spi2-core. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../11-at-spi2-core_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package at-spi2-core. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../11-at-spi2-core_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking at-spi2-core (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking at-spi2-core (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package fonts-noto-mono. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package fonts-noto-mono. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../12-fonts-noto-mono_20201225-1_all.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking fonts-noto-mono (20201225-1) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../12-fonts-noto-mono_20201225-1_all.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking fonts-noto-mono (20201225-1) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package fonts-urw-base35. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Selecting previously unselected package fonts-urw-base35. Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../13-fonts-urw-base35_20200910-7_all.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Preparing to unpack .../13-fonts-urw-base35_20200910-7_all.deb ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking fonts-urw-base35 (20200910-7) ... Jan 11 19:04:22 volumiox-mark volumio[1332]: info: Unpacking fonts-urw-base35 (20200910-7) ... Jan 11 19:04:22 volumiox-mark systemd[1]: Reloading. Jan 11 19:04:23 volumiox-mark sudo[5830]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Install script completed Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Adding reference to registry Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Done installing plugin. Jan 11 19:04:23 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 19:04:23 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Folder /tmp/plugins removed Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 19:04:23 volumiox-mark volumio[1332]: info: Folder /data/temp removed Jan 11 19:04:23 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:23 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:23 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Enabling plugin spop Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Loading plugin "spop"... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: PLUGIN START: spop Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:04:26 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Done. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:04:26 volumiox-mark volumio[1332]: info: No need to fix Spotify hosts Jan 11 19:04:26 volumiox-mark sudo[6066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:04:26 volumiox-mark sudo[6066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:26 volumiox-mark systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 19:04:26 volumiox-mark systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 19:04:26 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:26 volumiox-mark go-librespot[6068]: go-librespot daemon starting... Jan 11 19:04:26 volumiox-mark sudo[6066]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=debug msg="no app state found" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=debug msg="stored credentials not found" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=info msg="generated new device id: aeeb246011574653f6f7de4926fd82e4042f21c5" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatk1.0-0:armhf. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../14-libatk1.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libatk1.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatk1.0-0:armhf. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../14-libatk1.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libatk1.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatk-bridge2.0-0:armhf. Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../15-libatk-bridge2.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:26 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:26+07:00" level=info msg="zeroconf server listening on port 38523" Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libatk-bridge2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libatk-bridge2.0-0:armhf. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../15-libatk-bridge2.0-0_2.50.0-1~bpo12+rpt1_armhf.deb ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libatk-bridge2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libcolord2:armhf. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../16-libcolord2_1.4.6-2.2_armhf.deb ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Selecting previously unselected package libcolord2:armhf. Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Preparing to unpack .../16-libcolord2_1.4.6-2.2_armhf.deb ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libcolord2:armhf (1.4.6-2.2) ... Jan 11 19:04:26 volumiox-mark volumio[1332]: info: Unpacking libcolord2:armhf (1.4.6-2.2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libde265-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libde265-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../17-libde265-0_1.0.11-1+rpi1_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../17-libde265-0_1.0.11-1+rpi1_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libde265-0:armhf (1.0.11-1+rpi1) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libde265-0:armhf (1.0.11-1+rpi1) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf-xlib-2.0-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf-xlib-2.0-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../18-libgdk-pixbuf-xlib-2.0-0_2.40.2-2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf-xlib-2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../18-libgdk-pixbuf-xlib-2.0-0_2.40.2-2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf-xlib-2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf2.0-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf2.0-0:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../19-libgdk-pixbuf2.0-0_2.40.2-2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../19-libgdk-pixbuf2.0-0_2.40.2-2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf2.0-bin. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../20-libgdk-pixbuf2.0-bin_2.42.10+dfsg-1+deb12u2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgdk-pixbuf2.0-bin. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../20-libgdk-pixbuf2.0-bin_2.42.10+dfsg-1+deb12u2_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf2.0-bin (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgdk-pixbuf2.0-bin (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgif7:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgif7:armhf. Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../21-libgif7_5.2.1-2.5_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgif7:armhf (5.2.1-2.5) ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Preparing to unpack .../21-libgif7_5.2.1-2.5_armhf.deb ... Jan 11 19:04:27 volumiox-mark volumio[1332]: info: Unpacking libgif7:armhf (5.2.1-2.5) ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs-common. Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs-common. Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Preparing to unpack .../22-libgs-common_10.0.0~dfsg-11+deb12u8_all.deb ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Unpacking libgs-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Preparing to unpack .../22-libgs-common_10.0.0~dfsg-11+deb12u8_all.deb ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Unpacking libgs-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs10-common. Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs10-common. Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Preparing to unpack .../23-libgs10-common_10.0.0~dfsg-11+deb12u8_all.deb ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Unpacking libgs10-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Preparing to unpack .../23-libgs10-common_10.0.0~dfsg-11+deb12u8_all.deb ... Jan 11 19:04:28 volumiox-mark volumio[1332]: info: Unpacking libgs10-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:29 volumiox-mark volumio[1332]: info: go-librespot daemon successfully initialized Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libidn12:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../24-libidn12_1.41-1+b1_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libidn12:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../24-libidn12_1.41-1+b1_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libidn12:armhf (1.41-1+b1) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libidn12:armhf (1.41-1+b1) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libijs-0.35:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libijs-0.35:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../25-libijs-0.35_0.35-15_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libijs-0.35:armhf (0.35-15) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../25-libijs-0.35_0.35-15_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libijs-0.35:armhf (0.35-15) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libjbig2dec0:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libjbig2dec0:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../26-libjbig2dec0_0.19-3+b2_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libjbig2dec0:armhf (0.19-3+b2) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../26-libjbig2dec0_0.19-3+b2_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libjbig2dec0:armhf (0.19-3+b2) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpaper1:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpaper1:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../27-libpaper1_1.1.29_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libpaper1:armhf (1.1.29) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../27-libpaper1_1.1.29_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libpaper1:armhf (1.1.29) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs10:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgs10:armhf. Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../28-libgs10_10.0.0~dfsg-11+deb12u8_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Preparing to unpack .../28-libgs10_10.0.0~dfsg-11+deb12u8_armhf.deb ... Jan 11 19:04:31 volumiox-mark volumio[1332]: info: Unpacking libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:32 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:32 volumiox-mark go-librespot[6073]: time="2026-01-11T19:04:32+07:00" level=debug msg="new websocket client" Jan 11 19:04:32 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:33 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxdamage1:armhf. Jan 11 19:04:33 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxdamage1:armhf. Jan 11 19:04:33 volumiox-mark volumio[1332]: info: Preparing to unpack .../29-libxdamage1_1%3a1.1.6-1_armhf.deb ... Jan 11 19:04:33 volumiox-mark volumio[1332]: info: Unpacking libxdamage1:armhf (1:1.1.6-1) ... Jan 11 19:04:33 volumiox-mark volumio[1332]: info: Preparing to unpack .../29-libxdamage1_1%3a1.1.6-1_armhf.deb ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Unpacking libxdamage1:armhf (1:1.1.6-1) ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-common. Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-common. Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Preparing to unpack .../30-libgtk-3-common_1%3a3.24.38-2~deb12u2+rpt7+rpi1_all.deb ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Preparing to unpack .../30-libgtk-3-common_1%3a3.24.38-2~deb12u2+rpt7+rpi1_all.deb ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-0:armhf. Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-0:armhf. Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Preparing to unpack .../31-libgtk-3-0_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Preparing to unpack .../31-libgtk-3-0_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Jan 11 19:04:34 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-bin. Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Selecting previously unselected package libgtk-3-bin. Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Preparing to unpack .../32-libgtk-3-bin_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Preparing to unpack .../32-libgtk-3-bin_1%3a3.24.38-2~deb12u2+rpt7+rpi1_armhf.deb ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Unpacking libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:35 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 11 19:04:35 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:35 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Selecting previously unselected package libheif1:armhf. Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Selecting previously unselected package libheif1:armhf. Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Preparing to unpack .../33-libheif1_1.15.1-1+deb12u1_armhf.deb ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Unpacking libheif1:armhf (1.15.1-1+deb12u1) ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Preparing to unpack .../33-libheif1_1.15.1-1+deb12u1_armhf.deb ... Jan 11 19:04:35 volumiox-mark volumio[1332]: info: Unpacking libheif1:armhf (1.15.1-1+deb12u1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libspectre1:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libspectre1:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../34-libspectre1_0.2.12-1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libspectre1:armhf (0.2.12-1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../34-libspectre1_0.2.12-1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libspectre1:armhf (0.2.12-1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libwebpdemux2:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libwebpdemux2:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../35-libwebpdemux2_1.2.4-0.2+deb12u1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libwebpdemux2:armhf (1.2.4-0.2+deb12u1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../35-libwebpdemux2_1.2.4-0.2+deb12u1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libwebpdemux2:armhf (1.2.4-0.2+deb12u1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libimlib2:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libimlib2:armhf. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../36-libimlib2_1.10.0-4+deb12u1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libimlib2:armhf (1.10.0-4+deb12u1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../36-libimlib2_1.10.0-4+deb12u1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libimlib2:armhf (1.10.0-4+deb12u1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libobt2v5. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Selecting previously unselected package libobt2v5. Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../37-libobt2v5_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Unpacking libobt2v5 (3.6.1-10+rpt1) ... Jan 11 19:04:36 volumiox-mark volumio[1332]: info: Preparing to unpack .../37-libobt2v5_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libobt2v5 (3.6.1-10+rpt1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpangoxft-1.0-0:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../38-libpangoxft-1.0-0_1.50.12+ds-1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpangoxft-1.0-0:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../38-libpangoxft-1.0-0_1.50.12+ds-1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libobrender32v5. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../39-libobrender32v5_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libobrender32v5 (3.6.1-10+rpt1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libobrender32v5. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../39-libobrender32v5_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libobrender32v5 (3.6.1-10+rpt1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpaper-utils. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libpaper-utils. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../40-libpaper-utils_1.1.29_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libpaper-utils (1.1.29) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../40-libpaper-utils_1.1.29_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libpaper-utils (1.1.29) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package librsvg2-common:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package librsvg2-common:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../41-librsvg2-common_2.54.7+dfsg-1~deb12u1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../41-librsvg2-common_2.54.7+dfsg-1~deb12u1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxcb-util1:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxcb-util1:armhf. Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../42-libxcb-util1_0.4.0-1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libxcb-util1:armhf (0.4.0-1) ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Preparing to unpack .../42-libxcb-util1_0.4.0-1_armhf.deb ... Jan 11 19:04:37 volumiox-mark volumio[1332]: info: Unpacking libxcb-util1:armhf (0.4.0-1) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package libstartup-notification0:armhf. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../43-libstartup-notification0_0.12-6+b1_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package libstartup-notification0:armhf. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../43-libstartup-notification0_0.12-6+b1_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking libstartup-notification0:armhf (0.12-6+b1) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking libstartup-notification0:armhf (0.12-6+b1) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package obconf. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package obconf. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../44-obconf_1%3a2.0.4+git20150213-2+rpt4_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking obconf (1:2.0.4+git20150213-2+rpt4) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../44-obconf_1%3a2.0.4+git20150213-2+rpt4_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking obconf (1:2.0.4+git20150213-2+rpt4) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package openbox. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Selecting previously unselected package openbox. Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../45-openbox_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking openbox (3.6.1-10+rpt1) ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Preparing to unpack .../45-openbox_3.6.1-10+rpt1_armhf.deb ... Jan 11 19:04:38 volumiox-mark volumio[1332]: info: Unpacking openbox (3.6.1-10+rpt1) ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Selecting previously unselected package scrot. Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Selecting previously unselected package scrot. Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Preparing to unpack .../46-scrot_1.8.1-1_armhf.deb ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Unpacking scrot (1.8.1-1) ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Preparing to unpack .../46-scrot_1.8.1-1_armhf.deb ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Unpacking scrot (1.8.1-1) ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Setting up libpaper1:armhf (1.1.29) ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Setting up libpaper1:armhf (1.1.29) ... Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Received OAUTH Data Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Executing Spotify Oauth Login Jan 11 19:04:39 volumiox-mark volumio[1332]: info: Saving Spotify Refresh Token Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Creating config file /etc/papersize with new version Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Creating config file /etc/papersize with new version Jan 11 19:04:40 volumiox-mark sudo[6521]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 19:04:40 volumiox-mark sudo[6521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:40 volumiox-mark sudo[6521]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:40 volumiox-mark sudo[6523]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 19:04:40 volumiox-mark sudo[6523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:40 volumiox-mark volumio[1332]: info: New Spotify access tokenBQD3JTzeil... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 11 19:04:40 volumiox-mark sudo[6523]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:40 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to 192.168.1.205 from 192.168.1.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up gtk-update-icon-cache (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up gtk-update-icon-cache (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Received Get System Info Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Discovery: Getting this device information Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Listing playlists Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libxdamage1:armhf (1:1.1.6-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf-xlib-2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libxdamage1:armhf (1:1.1.6-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf-xlib-2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up fonts-noto-mono (20201225-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up fonts-noto-mono (20201225-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libpangoxft-1.0-0:armhf (1.50.12+ds-1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 19:04:40 volumiox-mark volumio[1332]: SPOTIFY: User informations: {"country":"TH","display_name":"Nattawut.wi","email":"nattawut.wi@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/orak2vj5lxh085v1xuz9ufk0b"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/orak2vj5lxh085v1xuz9ufk0b","id":"orak2vj5lxh085v1xuz9ufk0b","images":[],"product":"premium","type":"user","uri":"spotify:user:orak2vj5lxh085v1xuz9ufk0b"} Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:04:40 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up hicolor-icon-theme (0.17-2) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up hicolor-icon-theme (0.17-2) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libijs-0.35:armhf (0.35-15) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libwebpdemux2:armhf (1.2.4-0.2+deb12u1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libijs-0.35:armhf (0.35-15) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Setting up libwebpdemux2:armhf (1.2.4-0.2+deb12u1) ... Jan 11 19:04:40 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:04:40 volumiox-mark sudo[6533]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:04:40 volumiox-mark sudo[6533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:40 volumiox-mark systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 19:04:40 volumiox-mark systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 19:04:40 volumiox-mark systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:40 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:40 volumiox-mark sudo[6533]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:40 volumiox-mark go-librespot[6535]: go-librespot daemon starting... Jan 11 19:04:40 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:40+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:04:40 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:40+07:00" level=debug msg="app state loaded" Jan 11 19:04:40 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:40+07:00" level=debug msg="stored credentials not found" Jan 11 19:04:40 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket closed Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libgs-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libpaper-utils (1.1.29) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libatspi2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libgs-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libpaper-utils (1.1.29) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libatspi2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=info msg="zeroconf server listening on port 42443" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up fonts-urw-base35 (20200910-7) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up fonts-urw-base35 (20200910-7) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="obtained new client token: AACNTqQ8VOkNf1GrkzGf+EMyHvhbQf7MG10xt6CP2y+UkEtf9A4HY316fSWxsdH+VQpP2fDLAJrlUTKVfKMoLKBPrx3EsXnDIzqVopZk8TRT8gwwnrVd4VhlqS+MQ4GdEDVGSRhdG32VIxU5nnJRqFKANDYEeACpIFA/yOw6xz6GhPwzyI9uFEIWPuzP88/bzHKrm+7qcxPA6g+d1Uj7DNRIlmyjIbJejK0o4pYouIT2xxWcfqogTXc=" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: New Spotify access tokenBQCMF9pn56... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libcolord2:armhf (1.4.6-2.2) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libcolord2:armhf (1.4.6-2.2) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libxcb-util1:armhf (0.4.0-1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libxcb-util1:armhf (0.4.0-1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up poppler-data (0.4.12-1) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="completed keyexchange" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up poppler-data (0.4.12-1) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="completed challenge" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up dbus-user-session (1.14.10-1~deb12u1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up dbus-user-session (1.14.10-1~deb12u1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: SPOTIFY: User informations: {"country":"TH","display_name":"Nattawut.wi","email":"nattawut.wi@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/orak2vj5lxh085v1xuz9ufk0b"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/orak2vj5lxh085v1xuz9ufk0b","id":"orak2vj5lxh085v1xuz9ufk0b","images":[],"product":"premium","type":"user","uri":"spotify:user:orak2vj5lxh085v1xuz9ufk0b"} Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Spotify Successfully logged in Jan 11 19:04:41 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 19:04:41 volumiox-mark volumio[1332]: info: [1768133081565] CoreMusicLibrary::Adding element Spotify Jan 11 19:04:41 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 19:04:41 volumiox-mark volumio[1332]: Cannot find translation for source Spotify Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=info msg="authenticated AP" username="or*********************0b" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up adwaita-icon-theme (43-1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up adwaita-icon-theme (43-1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/share/icons/Adwaita/cursor.theme to provide /usr/share/icons/default/index.theme (x-cursor-theme) in auto mode Jan 11 19:04:41 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/share/icons/Adwaita/cursor.theme to provide /usr/share/icons/default/index.theme (x-cursor-theme) in auto mode Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=info msg="authenticated Login5" username="or*********************0b" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="stored credentials" username="or*********************0b" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="initializing zeroconf session" username="or*********************0b" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libjbig2dec0:armhf (0.19-3+b2) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libjbig2dec0:armhf (0.19-3+b2) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf2.0-0:armhf (2.40.2-2) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libidn12:armhf (1.41-1+b1) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="dealer connection opened" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=trace msg="starting accesspoint recv loop" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=trace msg="starting dealer recv loop" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=trace msg="received accesspoint ping" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=debug msg="received connection id: YTU0OTc0ZjgtNGRh...MzIxM0EwMzM1Qw==" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libidn12:armhf (1.41-1+b1) ... Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libobt2v5 (3.6.1-10+rpt1) ... Jan 11 19:04:41 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:41+07:00" level=trace msg="received accesspoint pong ack" Jan 11 19:04:41 volumiox-mark volumio[1332]: info: Setting up libobt2v5 (3.6.1-10+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up librsvg2-common:armhf (2.54.7+dfsg-1~deb12u1) ... Jan 11 19:04:42 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:42+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 19:04:42 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:42+07:00" level=debug msg="update volume requested to 65535/65535" Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgif7:armhf (5.2.1-2.5) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf2.0-bin (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgif7:armhf (5.2.1-2.5) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgdk-pixbuf2.0-bin (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up at-spi2-common (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libatk1.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Received Get System Info Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Discovery: Getting this device information Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:42 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up at-spi2-common (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libatk1.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:42+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 19:04:42 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:42+07:00" level=trace msg="emitting websocket event: volume" Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up fonts-droid-fallback (1:6.0.1r16-1.1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up fonts-droid-fallback (1:6.0.1r16-1.1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libde265-0:armhf (1.0.11-1+rpi1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libstartup-notification0:armhf (0.12-6+b1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libde265-0:armhf (1.0.11-1+rpi1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libstartup-notification0:armhf (0.12-6+b1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgs10-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libheif1:armhf (1.15.1-1+deb12u1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgs10-common (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libheif1:armhf (1.15.1-1+deb12u1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up dconf-service (0.40.0-4) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libatk-bridge2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up dconf-service (0.40.0-4) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libatk-bridge2.0-0:armhf (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up dconf-gsettings-backend:armhf (0.40.0-4) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libspectre1:armhf (0.2.12-1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libgs10:armhf (10.0.0~dfsg-11+deb12u8) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up dconf-gsettings-backend:armhf (0.40.0-4) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libspectre1:armhf (0.2.12-1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libimlib2:armhf (1.10.0-4+deb12u1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libobrender32v5 (3.6.1-10+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libimlib2:armhf (1.10.0-4+deb12u1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up libobrender32v5 (3.6.1-10+rpt1) ... Jan 11 19:04:42 volumiox-mark volumio[1332]: info: Setting up scrot (1.8.1-1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up gsettings-desktop-schemas (43.0-1+rpt1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up scrot (1.8.1-1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-common (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up gsettings-desktop-schemas (43.0-1+rpt1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up openbox (3.6.1-10+rpt1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up openbox (3.6.1-10+rpt1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/bin/openbox to provide /usr/bin/x-window-manager (x-window-manager) in auto mode Jan 11 19:04:43 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/bin/openbox-session to provide /usr/bin/x-session-manager (x-session-manager) in auto mode Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for libglib2.0-0:armhf (2.74.6-2+deb12u7) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/bin/openbox to provide /usr/bin/x-window-manager (x-window-manager) in auto mode Jan 11 19:04:43 volumiox-mark volumio[1332]: info: update-alternatives: using /usr/bin/openbox-session to provide /usr/bin/x-session-manager (x-session-manager) in auto mode Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for libglib2.0-0:armhf (2.74.6-2+deb12u7) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Received Get System Info Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Discovery: Getting this device information Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:43 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-0:armhf (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Setting up libgtk-3-bin (1:3.24.38-2~deb12u2+rpt7+rpi1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for man-db (2.11.2-2) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for man-db (2.11.2-2) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for shared-mime-info (2.2-1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Processing triggers for shared-mime-info (2.2-1) ... Jan 11 19:04:43 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:44 volumiox-mark volumio[1332]: info: go-librespot daemon successfully initialized Jan 11 19:04:44 volumiox-mark go-librespot[6536]: time="2026-01-11T19:04:44+07:00" level=debug msg="new websocket client" Jan 11 19:04:44 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:45 volumiox-mark volumio[1332]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 19:04:45 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 19:04:45 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:04:45 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:04:45 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:04:45 volumiox-mark sudo[6697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:04:45 volumiox-mark sudo[6697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:45 volumiox-mark systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 19:04:45 volumiox-mark systemd[1]: go-librespot-daemon.service: Killing process 6539 (go-librespot) with signal SIGKILL. Jan 11 19:04:45 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket closed Jan 11 19:04:45 volumiox-mark systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 19:04:45 volumiox-mark systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:45 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:45 volumiox-mark go-librespot[6699]: go-librespot daemon starting... Jan 11 19:04:45 volumiox-mark sudo[6697]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=debug msg="app state loaded" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 19:04:45 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:45+07:00" level=info msg="zeroconf server listening on port 41639" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="obtained new client token: AAD8ghpd8mElP18442jI3KEAR+vSucplKddlxIit9GnzKFgWt+xKvFB/+5j1YPrsEbqbcSYDKjH2Xz7hZ0fRRvmrAR2/RJklLLi5h+wnD1Tk7Lz5gIq7h0hcWKsFHCeKq3i6E/XYviBuoVzoryTuWWIRBMwqh3XVtofMBUczcXRzNW5yeI1QvHx1oxEBMIlP2EJI8bTv3C1nFRGtaXcHxpih1htUm5wVSBdFBZMWou92icLxUrGhBHAQvw==" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="completed keyexchange" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="completed challenge" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=info msg="authenticated AP" username="or*********************0b" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=info msg="authenticated Login5" username="or*********************0b" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="initializing zeroconf session" username="or*********************0b" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="dealer connection opened" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=trace msg="starting accesspoint recv loop" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=trace msg="starting dealer recv loop" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=trace msg="received accesspoint ping" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="received connection id: YzE4YTBkZGMtMDRi...RTgzNEYxNzY4OQ==" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=trace msg="received accesspoint pong ack" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="update volume requested to 65535/65535" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 19:04:46 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:46+07:00" level=trace msg="emitting websocket event: volume" Jan 11 19:04:47 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:47 volumiox-mark go-librespot[6700]: time="2026-01-11T19:04:47+07:00" level=debug msg="new websocket client" Jan 11 19:04:47 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:47 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:47 volumiox-mark volumio[1332]: info: Spotify volume: 100 Jan 11 19:04:47 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 19:04:47 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:47 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:48 volumiox-mark volumio[1332]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 19:04:48 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 19:04:48 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:04:48 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:04:48 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:04:48 volumiox-mark sudo[6711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:04:48 volumiox-mark sudo[6711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:48 volumiox-mark systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 19:04:48 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket closed Jan 11 19:04:48 volumiox-mark systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 19:04:48 volumiox-mark systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:48 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:48 volumiox-mark sudo[6711]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:48 volumiox-mark go-librespot[6713]: go-librespot daemon starting... Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="app state loaded" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=info msg="zeroconf server listening on port 38751" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="obtained new client token: AABXBh9UzsLZSNJyEBcc28NZvtg6ubFaalN1enM1gIC5CuNHGZ6y0QWK9OE+ySze6yjqdwiC+4ijkGRXS56HugJpcxU3YswTVJCuv1ZPIN03fIQ/ajn0IPQlN6oubcEdrw2yL46ONfw+2b9jK9SJYm8AGd0xh0BRorSR0mQvPZoqEsLLQl3fTiJhxALrrEUpeVbUKSfir0rKKrDEwEg42XwiFCJA/FsqPxzM3LR2KKHA/7oZkflCnHIwRA==" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="completed keyexchange" Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=debug msg="completed challenge" Jan 11 19:04:48 volumiox-mark volumio[1332]: info: go-librespot daemon successfully initialized Jan 11 19:04:48 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:48+07:00" level=info msg="authenticated AP" username="or*********************0b" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=info msg="authenticated Login5" username="or*********************0b" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="initializing zeroconf session" username="or*********************0b" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="dealer connection opened" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=trace msg="starting accesspoint recv loop" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=trace msg="received accesspoint ping" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=trace msg="starting dealer recv loop" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="received connection id: YjBhNzkyYzYtMThj...RTNBQTJFOUQwRA==" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=trace msg="received accesspoint pong ack" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="update volume requested to 65535/65535" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 19:04:49 volumiox-mark go-librespot[6714]: time="2026-01-11T19:04:49+07:00" level=trace msg="emitting websocket event: volume" Jan 11 19:04:50 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:50 volumiox-mark volumio[1332]: info: Spotify volume: 100 Jan 11 19:04:50 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 19:04:50 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:04:50 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:04:50 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:04:50 volumiox-mark sudo[6724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:04:50 volumiox-mark sudo[6724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:04:50 volumiox-mark systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 19:04:50 volumiox-mark systemd[1]: go-librespot-daemon.service: Killing process 6717 (go-librespot) with signal SIGKILL. Jan 11 19:04:50 volumiox-mark systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 19:04:50 volumiox-mark systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:50 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:04:50 volumiox-mark go-librespot[6726]: go-librespot daemon starting... Jan 11 19:04:50 volumiox-mark sudo[6724]: pam_unix(sudo:session): session closed for user root Jan 11 19:04:50 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:50+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:04:50 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:50+07:00" level=debug msg="app state loaded" Jan 11 19:04:50 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=info msg="zeroconf server listening on port 34669" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="obtained new client token: AADxps+hUsHih61J9fX6vTqXFPLjJMRcEv9uqNXJd1F60d+DZOaBe41yoYYGnZLRqJCN23q/a3RLQo6exp27ZahKYC+kh0ccN2o8f21wkMSUwwtvTN+2qd+IsAXDS0F4zHTR01itsx48KJp1waufaoVHMIvzBwnXDS7GtZ0YvDXCazZ0g0Qxk3vfR7+8Wh4sn8aBOxqUMFA6+yoURy5MXvvVtymhhdPx/iwiTufokpyCuID0YsguuPc=" Jan 11 19:04:51 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="new websocket client" Jan 11 19:04:51 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:51 volumiox-mark volumio[1332]: info: go-librespot daemon successfully initialized Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="completed keyexchange" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="completed challenge" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=info msg="authenticated AP" username="or*********************0b" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=info msg="authenticated Login5" username="or*********************0b" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="initializing zeroconf session" username="or*********************0b" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="dealer connection opened" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=trace msg="starting accesspoint recv loop" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=trace msg="starting dealer recv loop" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=trace msg="received accesspoint ping" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 19:04:51 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="received connection id: NTJkMDZjZjAtNjA5...NEExN0U0QzZEOQ==" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="new websocket client" Jan 11 19:04:51 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=trace msg="received accesspoint pong ack" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 19:04:51 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:51+07:00" level=debug msg="update volume requested to 65535/65535" Jan 11 19:04:52 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:52+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 19:04:52 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:52+07:00" level=trace msg="emitting websocket event: volume" Jan 11 19:04:52 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 11 19:04:52 volumiox-mark volumio[1332]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 11 19:04:52 volumiox-mark volumio[1332]: info: Setting Volumio Volume from Spotify: 100 Jan 11 19:04:52 volumiox-mark volumio[1332]: info: VolumeController::SetAlsaVolume100 Jan 11 19:04:52 volumiox-mark volumio[1332]: info: CoreStateMachine::pushState Jan 11 19:04:52 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:52 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioPushState Jan 11 19:04:52 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 11 19:04:52 volumiox-mark volumio[1332]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 11 19:04:52 volumiox-mark volumio[1332]: info: FusionDsp - Volumio is not playing Jan 11 19:04:52 volumiox-mark volumio[1332]: info: FusionDsp - Clipped samples monitor stopped Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Setting up at-spi2-core (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Setting up at-spi2-core (2.50.0-1~bpo12+rpt1) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Processing triggers for libgdk-pixbuf-2.0-0:armhf (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Processing triggers for libgdk-pixbuf-2.0-0:armhf (2.42.10+dfsg-1+deb12u2) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Processing triggers for fontconfig (2.14.1-4) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Processing triggers for fontconfig (2.14.1-4) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: go-librespot daemon successfully initialized Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Setting up obconf (1:2.0.4+git20150213-2+rpt4) ... Jan 11 19:04:53 volumiox-mark volumio[1332]: info: Setting up obconf (1:2.0.4+git20150213-2+rpt4) ... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Spotify volume: 100 Jan 11 19:04:54 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:54 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:54+07:00" level=debug msg="new websocket client" Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:54 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:54 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Creating /etc/X11/xorg.conf.d dir Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Creating /etc/X11/xorg.conf.d dir Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Creating Xorg configuration Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Installing Chromium Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Creating Xorg configuration Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Installing Chromium Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Reading package lists... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Reading package lists... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Building dependency tree... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Building dependency tree... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Reading state information... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Reading state information... Jan 11 19:04:54 volumiox-mark volumio[1332]: info: Spotify volume: 100 Jan 11 19:04:54 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 19:04:54 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:54 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: The following additional packages will be installed: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium chromium-codecs-ffmpeg-extra chromium-common chromium-l10n Jan 11 19:04:55 volumiox-mark volumio[1332]: info: The following additional packages will be installed: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium chromium-codecs-ffmpeg-extra chromium-common chromium-l10n Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-sandbox fonts-liberation libdouble-conversion3 libfile-basedir-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-sandbox fonts-liberation libdouble-conversion3 libfile-basedir-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libharfbuzz-subset0 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libio-stringy-perl libipc-system-simple-perl libnet-dbus-perl libnspr4 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libnss3 libopenh264-7 libtie-ixhash-perl libupower-glib3 libwidevinecdm0 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libx11-protocol-perl libxml-parser-perl libxml-twig-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libxml-xpathengine-perl libxnvctrl0 libxslt1.1 notification-daemon Jan 11 19:04:55 volumiox-mark volumio[1332]: info: rpi-chromium-mods upower xdg-utils zenoty Jan 11 19:04:55 volumiox-mark volumio[1332]: info: Suggested packages: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-shell chromium-driver libunicode-map8-perl libunicode-string-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: xml-twig-tools Jan 11 19:04:55 volumiox-mark volumio[1332]: info: The following NEW packages will be installed: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium chromium-browser chromium-codecs-ffmpeg-extra chromium-common Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-l10n chromium-sandbox fonts-liberation libdouble-conversion3 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libfile-basedir-perl libfile-desktopentry-perl libfile-mimeinfo-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libharfbuzz-subset0 libio-stringy-perl libipc-system-simple-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libnet-dbus-perl libnspr4 libnss3 libopenh264-7 libtie-ixhash-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libupower-glib3 libwidevinecdm0 libx11-protocol-perl libxml-parser-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libxml-twig-perl libxml-xpathengine-perl libxnvctrl0 libxslt1.1 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: notification-daemon rpi-chromium-mods upower xdg-utils zenoty Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libharfbuzz-subset0 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libio-stringy-perl libipc-system-simple-perl libnet-dbus-perl libnspr4 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libnss3 libopenh264-7 libtie-ixhash-perl libupower-glib3 libwidevinecdm0 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libx11-protocol-perl libxml-parser-perl libxml-twig-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libxml-xpathengine-perl libxnvctrl0 libxslt1.1 notification-daemon Jan 11 19:04:55 volumiox-mark volumio[1332]: info: rpi-chromium-mods upower xdg-utils zenoty Jan 11 19:04:55 volumiox-mark volumio[1332]: info: Suggested packages: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-shell chromium-driver libunicode-map8-perl libunicode-string-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: xml-twig-tools Jan 11 19:04:55 volumiox-mark volumio[1332]: info: The following NEW packages will be installed: Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium chromium-browser chromium-codecs-ffmpeg-extra chromium-common Jan 11 19:04:55 volumiox-mark volumio[1332]: info: chromium-l10n chromium-sandbox fonts-liberation libdouble-conversion3 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libfile-basedir-perl libfile-desktopentry-perl libfile-mimeinfo-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libharfbuzz-subset0 libio-stringy-perl libipc-system-simple-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libnet-dbus-perl libnspr4 libnss3 libopenh264-7 libtie-ixhash-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libupower-glib3 libwidevinecdm0 libx11-protocol-perl libxml-parser-perl Jan 11 19:04:55 volumiox-mark volumio[1332]: info: libxml-twig-perl libxml-xpathengine-perl libxnvctrl0 libxslt1.1 Jan 11 19:04:55 volumiox-mark volumio[1332]: info: notification-daemon rpi-chromium-mods upower xdg-utils zenoty Jan 11 19:04:56 volumiox-mark volumio[1332]: info: 0 upgraded, 32 newly installed, 0 to remove and 14 not upgraded. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Need to get 157 MB of archives. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: After this operation, 384 MB of additional disk space will be used. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-codecs-ffmpeg-extra armhf 126.0.6478.164-rpt1 [963 kB] Jan 11 19:04:56 volumiox-mark volumio[1332]: info: 0 upgraded, 32 newly installed, 0 to remove and 14 not upgraded. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Need to get 157 MB of archives. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: After this operation, 384 MB of additional disk space will be used. Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-codecs-ffmpeg-extra armhf 126.0.6478.164-rpt1 [963 kB] Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Get:2 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libdouble-conversion3 armhf 3.2.1-1 [38.8 kB] Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Get:2 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libdouble-conversion3 armhf 3.2.1-1 [38.8 kB] Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Initializing connection to go-librespot Websocket Jan 11 19:04:56 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:56+07:00" level=debug msg="new websocket client" Jan 11 19:04:56 volumiox-mark volumio[1332]: info: Connection to go-librespot Websocket established Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libharfbuzz-subset0 armhf 6.0.0+dfsg-3 [1881 kB] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libharfbuzz-subset0 armhf 6.0.0+dfsg-3 [1881 kB] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Spotify volume: 100 Jan 11 19:04:57 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 19:04:57 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:57 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf zenoty armhf 0.2 [7194 B] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf zenoty armhf 0.2 [7194 B] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:6 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-common armhf 1:143.0.7499.169-1~deb12u1+rpt1 [28.1 MB] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:6 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-common armhf 1:143.0.7499.169-1~deb12u1+rpt1 [28.1 MB] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:4 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libnspr4 armhf 2:4.35-1 [90.9 kB] Jan 11 19:04:57 volumiox-mark volumio[1332]: info: Get:4 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libnspr4 armhf 2:4.35-1 [90.9 kB] Jan 11 19:04:58 volumiox-mark volumio[1332]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libopenh264-7 armhf 2.3.1+dfsg-3+deb12u2 [315 kB] Jan 11 19:04:58 volumiox-mark volumio[1332]: info: Get:8 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libopenh264-7 armhf 2.3.1+dfsg-3+deb12u2 [315 kB] Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 19:04:58 volumiox-mark volumio[1332]: info: Received Get System Version Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 19:04:58 volumiox-mark volumio[1332]: info: Received Get System Info Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 19:04:58 volumiox-mark volumio[1332]: info: Discovery: Getting this device information Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:04:58 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 19:04:59 volumiox-mark volumio[1332]: info: Get:10 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxslt1.1 armhf 1.1.35-1+deb12u3 [209 kB] Jan 11 19:04:59 volumiox-mark volumio[1332]: info: Get:10 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxslt1.1 armhf 1.1.35-1+deb12u3 [209 kB] Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="handling transfer player command from 6342329f87664cb020324b469aefb603d9919a04" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="resolved context of track" uri="spotify:album:6PeEeHD5Rt2zNnrBQ93ccS" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=trace msg="fetched new page 0 with 14 items (list: 14)" uri="spotify:album:6PeEeHD5Rt2zNnrBQ93ccS" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="loading track (paused: false, position: 1503ms)" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=trace msg="emitting websocket event: will_play" Jan 11 19:04:59 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:6PeEeHD5Rt2zNnrBQ93ccS","uri":"spotify:track:78P21qcArDvQ1KV0fgh3n7","play_origin":""}} Jan 11 19:04:59 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:6PeEeHD5Rt2zNnrBQ93ccS","uri":"spotify:track:78P21qcArDvQ1KV0fgh3n7","play_origin":""}} Jan 11 19:04:59 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:6PeEeHD5Rt2zNnrBQ93ccS","uri":"spotify:track:78P21qcArDvQ1KV0fgh3n7","play_origin":""}} Jan 11 19:04:59 volumiox-mark volumio[1332]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:album:6PeEeHD5Rt2zNnrBQ93ccS","uri":"spotify:track:78P21qcArDvQ1KV0fgh3n7","play_origin":""}} Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1136" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="selected format OGG_VORBIS_320 (6db4de57a439190ccb4594daf7a9404094f8f504)" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=debug msg="requested aes key for file 6db4de57a439190ccb4594daf7a9404094f8f504, gid: 78P21qcArDvQ1KV0fgh3n7" Jan 11 19:04:59 volumiox-mark volumio[1332]: info: Get:11 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf xdg-utils all 1.1.3-4.1 [75.5 kB] Jan 11 19:04:59 volumiox-mark volumio[1332]: info: Get:11 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf xdg-utils all 1.1.3-4.1 [75.5 kB] Jan 11 19:04:59 volumiox-mark go-librespot[6727]: time="2026-01-11T19:04:59+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:04:59 volumiox-mark volumio[1332]: info: Getting Spotify volume Jan 11 19:04:59 volumiox-mark volumio[1332]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 19:04:59 volumiox-mark volumio[1332]: info: CoreCommandRouter::volumioGetState Jan 11 19:04:59 volumiox-mark volumio[1332]: info: CorePlayQueue::getTrack 0 Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="fetched first chunk of 22, total size is 11260783 bytes" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=trace msg="seek to 1503ms (diff: 32ms, samples: 66282, bytes: 69798)" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="created new output device" Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: camilladsp stopping service pid 3324... Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=info msg="loaded track \"ประชาธิปไตย\" (paused: false, position: 1503ms, duration: 268986ms, prefetched: false)" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark volumio[1332]: info: camilladsp service terminated, instance 1 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 11 19:05:00 volumiox-mark volumio[1332]: info: camilladsp service started and running in background, instance 1 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 11 19:05:00 volumiox-mark volumio[1332]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark volumio[1332]: error: FusionDsp - Reload WebSocket error: [object Object] Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 19:05:00 volumiox-mark volumio[1332]: info: Get:12 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf fonts-liberation all 1:1.07.4-11 [828 kB] Jan 11 19:05:00 volumiox-mark volumio[1332]: info: Get:12 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf fonts-liberation all 1:1.07.4-11 [828 kB] Jan 11 19:05:00 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:00+07:00" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:78P21qcArDvQ1KV0fgh3n7" Jan 11 19:05:00 volumiox-mark volumio[1332]: info: Get:13 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libipc-system-simple-perl all 1.30-2 [26.8 kB] Jan 11 19:05:00 volumiox-mark volumio[1332]: info: Get:13 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libipc-system-simple-perl all 1.30-2 [26.8 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:14 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-basedir-perl all 0.09-2 [15.1 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:14 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-basedir-perl all 0.09-2 [15.1 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:15 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-desktopentry-perl all 0.22-3 [17.6 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:15 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-desktopentry-perl all 0.22-3 [17.6 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:16 http://archive.raspberrypi.com/debian bookworm/main armhf chromium armhf 1:143.0.7499.169-1~deb12u1+rpt1 [94.8 MB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:16 http://archive.raspberrypi.com/debian bookworm/main armhf chromium armhf 1:143.0.7499.169-1~deb12u1+rpt1 [94.8 MB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libnss3 armhf 2:3.87.1-1+deb12u1 [1089 kB] Jan 11 19:05:01 volumiox-mark volumio[1332]: info: Get:7 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libnss3 armhf 2:3.87.1-1+deb12u1 [1089 kB] Jan 11 19:05:02 volumiox-mark volumio[1332]: info: Get:17 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-mimeinfo-perl all 0.33-1 [48.3 kB] Jan 11 19:05:02 volumiox-mark volumio[1332]: info: Get:17 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libfile-mimeinfo-perl all 0.33-1 [48.3 kB] Jan 11 19:05:03 volumiox-mark volumio[1332]: info: Get:18 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libio-stringy-perl all 2.111-3 [56.5 kB] Jan 11 19:05:03 volumiox-mark volumio[1332]: info: Get:18 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libio-stringy-perl all 2.111-3 [56.5 kB] Jan 11 19:05:04 volumiox-mark volumio[1332]: info: Get:19 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-parser-perl armhf 2.46-4 [197 kB] Jan 11 19:05:04 volumiox-mark volumio[1332]: info: Get:19 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-parser-perl armhf 2.46-4 [197 kB] Jan 11 19:05:04 volumiox-mark volumio[1332]: info: Get:20 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-twig-perl all 1:3.52-2 [181 kB] Jan 11 19:05:04 volumiox-mark volumio[1332]: info: Get:20 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-twig-perl all 1:3.52-2 [181 kB] Jan 11 19:05:05 volumiox-mark volumio[1332]: info: Get:21 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libnet-dbus-perl armhf 1.2.0-2 [174 kB] Jan 11 19:05:05 volumiox-mark volumio[1332]: info: Get:21 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libnet-dbus-perl armhf 1.2.0-2 [174 kB] Jan 11 19:05:05 volumiox-mark volumio[1332]: info: Get:22 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libtie-ixhash-perl all 1.23-4 [11.7 kB] Jan 11 19:05:05 volumiox-mark volumio[1332]: info: Get:22 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libtie-ixhash-perl all 1.23-4 [11.7 kB] Jan 11 19:05:06 volumiox-mark volumio[1332]: info: Get:23 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libupower-glib3 armhf 0.99.20-2 [29.8 kB] Jan 11 19:05:06 volumiox-mark volumio[1332]: info: Get:23 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libupower-glib3 armhf 0.99.20-2 [29.8 kB] Jan 11 19:05:06 volumiox-mark volumio[1332]: info: Get:24 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libx11-protocol-perl all 0.56-9 [120 kB] Jan 11 19:05:06 volumiox-mark volumio[1332]: info: Get:24 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libx11-protocol-perl all 0.56-9 [120 kB] Jan 11 19:05:07 volumiox-mark volumio[1332]: info: Get:25 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-xpathengine-perl all 0.14-2 [33.5 kB] Jan 11 19:05:07 volumiox-mark volumio[1332]: info: Get:25 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf libxml-xpathengine-perl all 0.14-2 [33.5 kB] Jan 11 19:05:07 volumiox-mark volumio[1332]: info: Get:26 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf notification-daemon armhf 3.20.0-4 [56.3 kB] Jan 11 19:05:07 volumiox-mark volumio[1332]: info: Get:26 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf notification-daemon armhf 3.20.0-4 [56.3 kB] Jan 11 19:05:08 volumiox-mark volumio[1332]: info: Get:27 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf upower armhf 0.99.20-2 [75.6 kB] Jan 11 19:05:08 volumiox-mark volumio[1332]: info: Get:27 http://mirror1.ku.ac.th/raspbian/raspbian bookworm/main armhf upower armhf 0.99.20-2 [75.6 kB] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:28 http://archive.raspberrypi.com/debian bookworm/main armhf rpi-chromium-mods armhf 20250506 [5078 B] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:28 http://archive.raspberrypi.com/debian bookworm/main armhf rpi-chromium-mods armhf 20250506 [5078 B] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:29 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-browser all 1:143.0.7499.169-1~deb12u1+rpt1 [109 kB] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:29 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-browser all 1:143.0.7499.169-1~deb12u1+rpt1 [109 kB] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:30 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-l10n all 1:143.0.7499.169-1~deb12u1+rpt1 [19.6 MB] Jan 11 19:05:10 volumiox-mark volumio[1332]: info: Get:30 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-l10n all 1:143.0.7499.169-1~deb12u1+rpt1 [19.6 MB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:9 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxnvctrl0 armhf 525.85.05-3~deb12u1 [12.3 kB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:9 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxnvctrl0 armhf 525.85.05-3~deb12u1 [12.3 kB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:31 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-sandbox armhf 1:143.0.7499.169-1~deb12u1+rpt1 [115 kB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:32 http://archive.raspberrypi.com/debian bookworm/main armhf libwidevinecdm0 armhf 4.10.2662.3+1 [7466 kB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:31 http://archive.raspberrypi.com/debian bookworm/main armhf chromium-sandbox armhf 1:143.0.7499.169-1~deb12u1+rpt1 [115 kB] Jan 11 19:05:13 volumiox-mark volumio[1332]: info: Get:32 http://archive.raspberrypi.com/debian bookworm/main armhf libwidevinecdm0 armhf 4.10.2662.3+1 [7466 kB] Jan 11 19:05:16 volumiox-mark volumio[1332]: info: Fetched 157 MB in 21s (7503 kB/s) Jan 11 19:05:16 volumiox-mark volumio[1332]: info: Fetched 157 MB in 21s (7503 kB/s) Jan 11 19:05:17 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium-codecs-ffmpeg-extra. Jan 11 19:05:17 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium-codecs-ffmpeg-extra. Jan 11 19:05:17 volumiox-mark volumio[1332]: [620B blob data] Jan 11 19:05:17 volumiox-mark volumio[1332]: info: Preparing to unpack .../00-chromium-codecs-ffmpeg-extra_126.0.6478.164-rpt1_armhf.deb ... Jan 11 19:05:17 volumiox-mark volumio[1332]: [620B blob data] Jan 11 19:05:17 volumiox-mark volumio[1332]: info: Preparing to unpack .../00-chromium-codecs-ffmpeg-extra_126.0.6478.164-rpt1_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking chromium-codecs-ffmpeg-extra (126.0.6478.164-rpt1) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libdouble-conversion3:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libdouble-conversion3:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../01-libdouble-conversion3_3.2.1-1_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libdouble-conversion3:armhf (3.2.1-1) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../01-libdouble-conversion3_3.2.1-1_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libdouble-conversion3:armhf (3.2.1-1) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libharfbuzz-subset0:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libharfbuzz-subset0:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../02-libharfbuzz-subset0_6.0.0+dfsg-3_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../02-libharfbuzz-subset0_6.0.0+dfsg-3_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libharfbuzz-subset0:armhf (6.0.0+dfsg-3) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libnspr4:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Selecting previously unselected package libnspr4:armhf. Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../03-libnspr4_2%3a4.35-1_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libnspr4:armhf (2:4.35-1) ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Preparing to unpack .../03-libnspr4_2%3a4.35-1_armhf.deb ... Jan 11 19:05:18 volumiox-mark volumio[1332]: info: Unpacking libnspr4:armhf (2:4.35-1) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libnss3:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../04-libnss3_2%3a3.87.1-1+deb12u1_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libnss3:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../04-libnss3_2%3a3.87.1-1+deb12u1_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libnss3:armhf (2:3.87.1-1+deb12u1) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libnss3:armhf (2:3.87.1-1+deb12u1) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libopenh264-7:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libopenh264-7:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../05-libopenh264-7_2.3.1+dfsg-3+deb12u2_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../05-libopenh264-7_2.3.1+dfsg-3+deb12u2_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libopenh264-7:armhf (2.3.1+dfsg-3+deb12u2) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxnvctrl0:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxnvctrl0:armhf. Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../06-libxnvctrl0_525.85.05-3~deb12u1_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Preparing to unpack .../06-libxnvctrl0_525.85.05-3~deb12u1_armhf.deb ... Jan 11 19:05:19 volumiox-mark volumio[1332]: info: Unpacking libxnvctrl0:armhf (525.85.05-3~deb12u1) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxslt1.1:armhf. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../07-libxslt1.1_1.1.35-1+deb12u3_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking libxslt1.1:armhf (1.1.35-1+deb12u3) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package libxslt1.1:armhf. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../07-libxslt1.1_1.1.35-1+deb12u3_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking libxslt1.1:armhf (1.1.35-1+deb12u3) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package xdg-utils. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../08-xdg-utils_1.1.3-4.1_all.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking xdg-utils (1.1.3-4.1) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package xdg-utils. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../08-xdg-utils_1.1.3-4.1_all.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking xdg-utils (1.1.3-4.1) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package zenoty. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package zenoty. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../09-zenoty_0.2_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking zenoty (0.2) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../09-zenoty_0.2_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking zenoty (0.2) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium-common. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../10-chromium-common_1%3a143.0.7499.169-1~deb12u1+rpt1_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium-common. Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Preparing to unpack .../10-chromium-common_1%3a143.0.7499.169-1~deb12u1+rpt1_armhf.deb ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking chromium-common (1:143.0.7499.169-1~deb12u1+rpt1) ... Jan 11 19:05:20 volumiox-mark volumio[1332]: info: Unpacking chromium-common (1:143.0.7499.169-1~deb12u1+rpt1) ... Jan 11 19:05:21 volumiox-mark go-librespot[6727]: time="2026-01-11T19:05:21+07:00" level=trace msg="sent dealer ping" Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium. Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Selecting previously unselected package chromium. Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Preparing to unpack .../11-chromium_1%3a143.0.7499.169-1~deb12u1+rpt1_armhf.deb ... Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Unpacking chromium (1:143.0.7499.169-1~deb12u1+rpt1) ... Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Preparing to unpack .../11-chromium_1%3a143.0.7499.169-1~deb12u1+rpt1_armhf.deb ... Jan 11 19:05:24 volumiox-mark volumio[1332]: info: Unpacking chromium (1:143.0.7499.169-1~deb12u1+rpt1) ... Jan 11 19:05:33 volumiox-mark volumio[1332]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 19:05:33 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 19:05:33 volumiox-mark volumio[1332]: info: Creating Spotify config file Jan 11 19:05:33 volumiox-mark volumio[1332]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 19:05:35 volumiox-mark volumio[1332]: info: Spotify config file written Jan 11 19:05:35 volumiox-mark sudo[32125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 19:05:35 volumiox-mark sudo[32125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 19:05:35 volumiox-mark systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 19:05:35 volumiox-mark systemd[1]: go-librespot-daemon.service: Killing process 6732 (go-librespot) with signal SIGKILL. Jan 11 19:05:35 volumiox-mark systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 19:05:35 volumiox-mark volumio[1332]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 19:05:35 volumiox-mark systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:05:35 volumiox-mark volumio[1332]: Error: socket hang up Jan 11 19:05:35 volumiox-mark volumio[1332]: at connResetException (node:internal/errors:720:14) Jan 11 19:05:35 volumiox-mark volumio[1332]: at Socket.socketOnEnd (node:_http_client:519:23) Jan 11 19:05:35 volumiox-mark volumio[1332]: at Socket.emit (node:events:526:35) Jan 11 19:05:35 volumiox-mark volumio[1332]: at endReadableNT (node:internal/streams/readable:1376:12) Jan 11 19:05:35 volumiox-mark volumio[1332]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jan 11 19:05:35 volumiox-mark volumio[1332]: code: 'ECONNRESET', Jan 11 19:05:35 volumiox-mark volumio[1332]: response: undefined Jan 11 19:05:35 volumiox-mark volumio[1332]: } Jan 11 19:05:35 volumiox-mark volumio[1332]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 19:05:35 volumiox-mark systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 19:05:35 volumiox-mark go-librespot[32138]: go-librespot daemon starting... Jan 11 19:05:35 volumiox-mark sudo[32125]: pam_unix(sudo:session): session closed for user root Jan 11 19:05:35 volumiox-mark go-librespot[32150]: time="2026-01-11T19:05:35+07:00" level=info msg="running go-librespot 0.6.2" Jan 11 19:05:35 volumiox-mark go-librespot[32150]: time="2026-01-11T19:05:35+07:00" level=debug msg="app state loaded" Jan 11 19:05:35 volumiox-mark go-librespot[32150]: time="2026-01-11T19:05:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 19:05:35 volumiox-mark sudo[32160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 19:04' Jan 11 19:05:35 volumiox-mark sudo[32160]: 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"