Mar 28 10:07:06 volumio go-librespot[1369]: time="2026-03-28T10:07:06+09:00" level=trace msg="sent dealer ping" Mar 28 10:07:06 volumio go-librespot[1369]: time="2026-03-28T10:07:06+09:00" level=trace msg="received dealer pong" Mar 28 10:07:12 volumio volumio[1115]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Mar 28 10:07:12 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:12.513+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:16 volumio volumio[1115]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Mar 28 10:07:16 volumio go-librespot[1369]: time="2026-03-28T10:07:16+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 28 10:07:16 volumio volumio[1115]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Mar 28 10:07:16 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 10:07:16 volumio sudo[1730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Mar 28 10:07:16 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:07:16 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:16.684+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:16 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:16.714+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:16 volumio volumio[1115]: info: Connection to go-librespot Websocket closed Mar 28 10:07:16 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 10:07:16 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 10:07:16 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 10:07:16 volumio 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. Mar 28 10:07:16 volumio 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. Mar 28 10:07:16 volumio 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. Mar 28 10:07:16 volumio 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. Mar 28 10:07:16 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root Mar 28 10:07:21 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:21.713+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:21 volumio volumio[1115]: info: Check plugin dependencies Mar 28 10:07:22 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 10:07:22 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:22.229+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:22 volumio volumio[1115]: info: Rename folder Mar 28 10:07:22 volumio volumio[1115]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 10:07:22 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:22.413+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:22 volumio volumio[1115]: info: Move to category Mar 28 10:07:27 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:27.237+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:27 volumio volumio[1115]: info: Checking if install.sh is present Mar 28 10:07:27 volumio volumio[1115]: info: Executing install.sh Mar 28 10:07:27 volumio sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Mar 28 10:07:27 volumio sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:07:28 volumio volumio[1115]: info: Installing Go-librespot Mar 28 10:07:28 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:28.076+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:28 volumio volumio[1115]: info: Checking old vollibrespot installs Mar 28 10:07:28 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:28.182+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:28 volumio systemd[1]: Reloading. Mar 28 10:07:30 volumio volumio[1115]: info: Downloading daemon Mar 28 10:07:30 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:30.463+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:30 volumio 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. Mar 28 10:07:38 volumio volumio[1115]: info: Creating directories Mar 28 10:07:38 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:38.366+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:38 volumio volumio[1115]: info: Creating Start Script Mar 28 10:07:38 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:38.466+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:38 volumio systemd[1]: Reloading. Mar 28 10:07:40 volumio sudo[1775]: pam_unix(sudo:session): session closed for user root Mar 28 10:07:40 volumio volumio[1115]: Plugin install end detected on script Mar 28 10:07:40 volumio volumio[1115]: info: Install script completed Mar 28 10:07:40 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:40.479+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:40 volumio volumio5-onboarding[1559]: time=2026-03-28T10:07:40.544+09:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 28 10:07:40 volumio volumio[1115]: info: Done installing plugin. Mar 28 10:07:40 volumio volumio[1115]: info: Enabling plugin spop Mar 28 10:07:40 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 10:07:40 volumio volumio[1115]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false}] Mar 28 10:07:40 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 10:07:40 volumio volumio[1115]: info: Folder /tmp/plugins removed Mar 28 10:07:40 volumio volumio[1115]: info: Folder /tmp/downloaded_plugin.zip removed Mar 28 10:07:40 volumio volumio[1115]: info: Folder /data/temp removed Mar 28 10:07:46 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 10:08:10 volumio volumio[1115]: info: CALLMETHOD: music_service spop logout Mar 28 10:08:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: spop , logout Mar 28 10:08:10 volumio volumio[1115]: info: Deleting Spotify credentials File Mar 28 10:08:10 volumio volumio[1115]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Mar 28 10:08:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 10:08:11 volumio volumio[1115]: info: Creating Spotify config file Mar 28 10:08:11 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 10:08:11 volumio volumio[1115]: info: Spotify config file written Mar 28 10:08:11 volumio sudo[1907]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 10:08:11 volumio sudo[1907]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:08:11 volumio 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. Mar 28 10:08:11 volumio 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. Mar 28 10:08:11 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 10:08:11 volumio go-librespot[1909]: go-librespot daemon starting... Mar 28 10:08:11 volumio sudo[1907]: pam_unix(sudo:session): session closed for user root Mar 28 10:08:11 volumio go-librespot[1910]: time="2026-03-28T10:08:11+09:00" level=info msg="running go-librespot 0.7.1" Mar 28 10:08:11 volumio go-librespot[1910]: time="2026-03-28T10:08:11+09:00" level=debug msg="no app state found" Mar 28 10:08:11 volumio go-librespot[1910]: time="2026-03-28T10:08:11+09:00" level=debug msg="stored credentials not found" Mar 28 10:08:11 volumio go-librespot[1910]: time="2026-03-28T10:08:11+09:00" level=info msg="generated new device id: 977dacfb89881f3696edbecf191440b8591ae9d7" Mar 28 10:08:11 volumio go-librespot[1910]: time="2026-03-28T10:08:11+09:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 10:08:12 volumio go-librespot[1910]: time="2026-03-28T10:08:12+09: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]" Mar 28 10:08:12 volumio go-librespot[1910]: time="2026-03-28T10:08:12+09: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]" Mar 28 10:08:12 volumio go-librespot[1910]: time="2026-03-28T10:08:12+09: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]" Mar 28 10:08:12 volumio go-librespot[1910]: time="2026-03-28T10:08:12+09:00" level=info msg="zeroconf server listening on port 43541" Mar 28 10:08:12 volumio go-librespot[1910]: time="2026-03-28T10:08:12+09:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 10:08:15 volumio volumio[1115]: info: go-librespot daemon successfully initialized Mar 28 10:08:18 volumio volumio[1115]: info: Initializing connection to go-librespot Websocket Mar 28 10:08:18 volumio go-librespot[1910]: time="2026-03-28T10:08:18+09:00" level=debug msg="new websocket client" Mar 28 10:08:18 volumio volumio[1115]: info: Connection to go-librespot Websocket established Mar 28 10:08:18 volumio volumio[1115]: info: Received OAUTH Data Mar 28 10:08:18 volumio volumio[1115]: info: Executing Spotify Oauth Login Mar 28 10:08:18 volumio volumio[1115]: info: Saving Spotify Refresh Token Mar 28 10:08:19 volumio volumio[1115]: info: New Spotify access tokenBQCBOx-Rsi... Mar 28 10:08:19 volumio volumio[1115]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 28 10:08:20 volumio volumio[1115]: SPOTIFY: User informations: {"country":"KR","display_name":"emckk","email":"emckk@yahoo.co.kr","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/emckk"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/emckk","id":"emckk","images":[],"product":"premium","type":"user","uri":"spotify:user:emckk"} Mar 28 10:08:20 volumio volumio[1115]: info: Creating Spotify config file Mar 28 10:08:20 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 10:08:20 volumio sudo[1936]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 10:08:20 volumio sudo[1936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:08:20 volumio sudo[1938]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 10:08:20 volumio sudo[1938]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:08:20 volumio sudo[1938]: pam_unix(sudo:session): session closed for user root Mar 28 10:08:20 volumio sudo[1936]: pam_unix(sudo:session): session closed for user root Mar 28 10:08:20 volumio volumio[1115]: info: Spotify config file written Mar 28 10:08:20 volumio volumio[1115]: verbose: New Socket.io Connection to 192.168.88.23 from 192.168.88.14 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 28 10:08:20 volumio sudo[1942]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 10:08:20 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 10:08:21 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 10:08:21 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 10:08:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 10:08:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 10:08:21 volumio go-librespot[1945]: go-librespot daemon starting... Mar 28 10:08:21 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Mar 28 10:08:21 volumio volumio[1115]: info: Getting Spotify volume Mar 28 10:08:21 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=info msg="running go-librespot 0.7.1" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="app state loaded" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="stored credentials not found" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 10:08:21 volumio volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 10:08:21 volumio volumio[1115]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 10:08:21 volumio volumio[1115]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 28 10:08:21 volumio volumio[1115]: errno: -111, Mar 28 10:08:21 volumio volumio[1115]: code: 'ECONNREFUSED', Mar 28 10:08:21 volumio volumio[1115]: syscall: 'connect', Mar 28 10:08:21 volumio volumio[1115]: address: '127.0.0.1', Mar 28 10:08:21 volumio volumio[1115]: port: 9879, Mar 28 10:08:21 volumio volumio[1115]: response: undefined Mar 28 10:08:21 volumio volumio[1115]: } Mar 28 10:08:21 volumio volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09: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]" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09: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]" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09: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]" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=info msg="zeroconf server listening on port 46855" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="obtained new client token: AADXBWkx86Fylt1Hfr2SYm+oVW+TIY2nOinZKtp0X4gnRg5wKgAGk0g9gHg01wWgLDmLsVeQm/ofriwOolQNrNwRGmEtH7CBLkq0PiADfBqPcNhtY3xIU70e5V7Y3x4L1cpoBvuZjm4/AJYY6o91t8L5lMKgNuZAXsDTEcbZhNnSstpbJL66EBQh5BuGfBCyveNX6DtFYLkSAXEsJ8WOoQKJLU3ywFnPCXAHUg4Cj8uFDOAgYRNjziWeIw==" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="completed keyexchange" Mar 28 10:08:21 volumio go-librespot[1946]: time="2026-03-28T10:08:21+09:00" level=debug msg="completed challenge" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=info msg="authenticated AP" username="em*kk" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=info msg="authenticated Login5" username="em*kk" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="stored credentials" username="em*kk" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="initializing zeroconf session" username="em*kk" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="dealer connection opened" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=trace msg="starting accesspoint recv loop" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=trace msg="starting dealer recv loop" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=trace msg="received accesspoint ping" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="received connection id: ZmZlYWQ4YjQtZjdk...MUZEOTI2NEE4Ng==" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=trace msg="received accesspoint pong ack" Mar 28 10:08:22 volumio go-librespot[1946]: time="2026-03-28T10:08:22+09:00" level=debug msg="put connect state because NEW_DEVICE" Mar 28 10:08:27 volumio sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 10:07' Mar 28 10:08:27 volumio sudo[1969]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"