Dec 22 20:09:00 volumio sudo[6006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:09:00 volumio sudo[6006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:00 volumio sudo[6006]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:00 volumio sudo[6008]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:09:00 volumio sudo[6008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:00 volumio sudo[6008]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:18 volumio volumio[1137]: info: Received OAUTH Data Dec 22 20:09:18 volumio volumio[1137]: info: Executing Spotify Oauth Login Dec 22 20:09:18 volumio volumio[1137]: info: Saving Spotify Refresh Token Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: BQDSOMHbL-i7QXaC-HUZdslMBzw5sSwFDilAHcidVFrA0Ak1XDFr_uGw0CUom20ytnnJzJYRG51jSRnOq5lYwIP15USmW_ICPG_qvV0skImlKPmZty4MmGV7Q8Y-fa-NZAHQkD3OlpUaepUKstVPJTc6A3zfIPxICy7zFWM7lrDWnBwkBBC5lF8SVyrH5ryWHuJNtsTzjxOrOsXARKcD4y9_x5YBCgm3AotKdXJGtaMxc7KtPxbrmOSVhChbqvzsAVl2bQlRezEB-wH-CFBE3byQkCtJSl-PycfUNrXXTFuvao69Z7B7TMnB Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:09:18 volumio volumio[1137]: info: New Spotify access token = BQDSOMHbL-i7QXaC-HUZdslMBzw5sSwFDilAHcidVFrA0Ak1XDFr_uGw0CUom20ytnnJzJYRG51jSRnOq5lYwIP15USmW_ICPG_qvV0skImlKPmZty4MmGV7Q8Y-fa-NZAHQkD3OlpUaepUKstVPJTc6A3zfIPxICy7zFWM7lrDWnBwkBBC5lF8SVyrH5ryWHuJNtsTzjxOrOsXARKcD4y9_x5YBCgm3AotKdXJGtaMxc7KtPxbrmOSVhChbqvzsAVl2bQlRezEB-wH-CFBE3byQkCtJSl-PycfUNrXXTFuvao69Z7B7TMnB Dec 22 20:09:18 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: User informations: {"country":"PL","display_name":"Adrian Bogacki","email":"adriangamingteam14@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dhxjgfm6rjcz3225cmw7d2u7ci"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dhxjgfm6rjcz3225cmw7d2u7ci","id":"31dhxjgfm6rjcz3225cmw7d2u7ci","images":[],"product":"free","type":"user","uri":"spotify:user:31dhxjgfm6rjcz3225cmw7d2u7ci"} Dec 22 20:09:18 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:09:18 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:09:18 volumio volumio[1137]: info: Spotify config file written Dec 22 20:09:18 volumio sudo[6066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:09:18 volumio sudo[6066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:18 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:09:18 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:09:18 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:09:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:09:18 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:09:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:09:18 volumio sudo[6066]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:18 volumio go-librespot[6068]: go-librespot daemon starting... Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: BQBwmrza7ozZteM1KLfudaOAFKF1xZmvXgz2Aw4OEqAPnR8vQdeIxjXBpUbmG_ZskXR_tsHAOuJw0d8duJ-khC1PAQpPWWDKokUydYKSKJokjRO6miX6Pqd0f6ngZyoeqV2MbZzs8jIIv6gnF-kKanqEad3tLq5zH2OQqqiIjAzxi8_ysQ_t_XKG4s5B4hm7MSmM3zwOw-lwBYcqqxdKlkA9aNUyIyu5ucv5aKN-iKTjip0uc9IuHlL7O0gxT5SlnlhDctPaCkK8LzGdg99DLYTVIE4bTya3OHa3hrKqFsL2pxucdHK1SIoG Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:09:18 volumio volumio[1137]: info: New Spotify access token = BQBwmrza7ozZteM1KLfudaOAFKF1xZmvXgz2Aw4OEqAPnR8vQdeIxjXBpUbmG_ZskXR_tsHAOuJw0d8duJ-khC1PAQpPWWDKokUydYKSKJokjRO6miX6Pqd0f6ngZyoeqV2MbZzs8jIIv6gnF-kKanqEad3tLq5zH2OQqqiIjAzxi8_ysQ_t_XKG4s5B4hm7MSmM3zwOw-lwBYcqqxdKlkA9aNUyIyu5ucv5aKN-iKTjip0uc9IuHlL7O0gxT5SlnlhDctPaCkK8LzGdg99DLYTVIE4bTya3OHa3hrKqFsL2pxucdHK1SIoG Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:09:18 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=debug msg="app state loaded" Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=debug msg="stored credentials not found" Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:09:18 volumio volumio[1137]: SPOTIFY: User informations: {"country":"PL","display_name":"Adrian Bogacki","email":"adriangamingteam14@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dhxjgfm6rjcz3225cmw7d2u7ci"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dhxjgfm6rjcz3225cmw7d2u7ci","id":"31dhxjgfm6rjcz3225cmw7d2u7ci","images":[],"product":"free","type":"user","uri":"spotify:user:31dhxjgfm6rjcz3225cmw7d2u7ci"} Dec 22 20:09:18 volumio volumio[1137]: info: Spotify Successfully logged in Dec 22 20:09:18 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 20:09:18 volumio volumio[1137]: info: [1766430558865] CoreMusicLibrary::Adding element Spotify Dec 22 20:09:18 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:09:18 volumio volumio[1137]: Cannot find translation for source Spotify Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 22 20:09:18 volumio go-librespot[6069]: time="2025-12-22T20:09:18+01:00" level=info msg="zeroconf server listening on port 35867" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="obtained new client token: AAA1hCEcY1+7pCgaF/IO+AQ5VFjG0TtVf5tq2GJGSt6lKd58WkJa53SLiKBgMjiizG1ex85Pb7htvSm0c8QCVW0NKkGpw4AoLupEqMx+qN4qOy9M7WWwxjhjb2FeDPFmeJao/C1sTy8KuLfEYUJjGCfzCui+TWa8n7H5YwbDwKpM4h+F9MfLA0nOJS67uhiIhqDgnjOLW1IHGd/81q8cP9lB++a2mU3ozZv8Yb66fudhisev71PeXw==" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="completed keyexchange" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="completed challenge" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=info msg="authenticated AP" username="31************************ci" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=info msg="authenticated Login5" username="31************************ci" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="stored credentials" username="31************************ci" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="dealer connection opened" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=trace msg="starting accesspoint recv loop" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=trace msg="starting dealer recv loop" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=trace msg="received accesspoint ping" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="received connection id: MzIxYzk3ZmYtYTY0...NjQxQzM3RjlBRQ==" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=trace msg="received accesspoint pong ack" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="update volume requested to 65535/65535" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 22 20:09:19 volumio go-librespot[6069]: time="2025-12-22T20:09:19+01:00" level=trace msg="emitting websocket event: volume" Dec 22 20:09:21 volumio sudo[6082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:09:21 volumio sudo[6082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:21 volumio sudo[6084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:09:21 volumio sudo[6084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:21 volumio sudo[6082]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:21 volumio sudo[6084]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:21 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:09:21 volumio go-librespot[6069]: time="2025-12-22T20:09:21+01:00" level=debug msg="new websocket client" Dec 22 20:09:21 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:09:21 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 22 20:09:22 volumio volumio[1137]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Pro Build/RP1A.200720.011) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6312.118 Mobile Safari/537.36 XiaoMi/MiuiBrowser/14.47.1-gn Engine version: 3 Transport: polling Total Clients: 8 Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 20:09:23 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:23 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:23 volumio volumio[1137]: info: Listing playlists Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:23 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:23 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:23 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 20:09:24 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:09:24 volumio volumio[1137]: info: Spotify volume: 100 Dec 22 20:09:24 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:09:24 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:09:24 volumio go-librespot[6069]: time="2025-12-22T20:09:24+01:00" level=debug msg="new websocket client" Dec 22 20:09:24 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:09:24 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:25 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:26 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:26 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:26 volumio sudo[6095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:09:26 volumio sudo[6095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:26 volumio sudo[6095]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:26 volumio sudo[6097]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:09:26 volumio sudo[6097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:26 volumio sudo[6097]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:26 volumio volumio[1137]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Pro) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/123.0.6312.118 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:09:26 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:27 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:27 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:27 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:27 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:27 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:09:27 volumio volumio[1137]: info: Spotify volume: 100 Dec 22 20:09:27 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:09:27 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:28 volumio volumio[1137]: info: CALLMETHOD: music_service spop logout Dec 22 20:09:28 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: spop , logout Dec 22 20:09:28 volumio volumio[1137]: info: Deleting Spotify credentials File Dec 22 20:09:28 volumio volumio[1137]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 22 20:09:28 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:09:29 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:09:29 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:09:29 volumio volumio[1137]: info: Spotify config file written Dec 22 20:09:29 volumio sudo[6124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:09:29 volumio sudo[6124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:29 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:09:29 volumio systemd[1]: go-librespot-daemon.service: Killing process 6076 (go-librespot) with signal SIGKILL. Dec 22 20:09:29 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:09:29 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:09:29 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:09:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:09:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:09:29 volumio sudo[6124]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:29 volumio go-librespot[6126]: go-librespot daemon starting... Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=debug msg="no app state found" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=debug msg="stored credentials not found" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=info msg="generated new device id: b1676b01b91eb1543a7a41e6decfec3204f423be" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 22 20:09:29 volumio go-librespot[6127]: time="2025-12-22T20:09:29+01:00" level=info msg="zeroconf server listening on port 38027" Dec 22 20:09:32 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 20:09:32 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:09:32 volumio go-librespot[6127]: time="2025-12-22T20:09:32+01:00" level=debug msg="new websocket client" Dec 22 20:09:32 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:09:32 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 22 20:09:35 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:09:35 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:09:35 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:09:35 volumio go-librespot[6127]: time="2025-12-22T20:09:35+01:00" level=debug msg="new websocket client" Dec 22 20:09:35 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:09:35 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:38 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:09:38 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 22 20:09:38 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:41 volumio volumio[1137]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Pro Build/RP1A.200720.011; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::volumioGetQueue Dec 22 20:09:41 volumio volumio[1137]: info: CoreStateMachine::getQueue Dec 22 20:09:41 volumio volumio[1137]: info: CorePlayQueue::getQueue Dec 22 20:09:41 volumio volumio[1137]: info: Listing playlists Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 20:09:41 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:41 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:41 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:42 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 20:09:45 volumio sudo[6167]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:09:45 volumio sudo[6167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:45 volumio sudo[6169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:09:45 volumio sudo[6169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:09:45 volumio sudo[6167]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:45 volumio sudo[6169]: pam_unix(sudo:session): session closed for user root Dec 22 20:09:45 volumio volumio[1137]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Pro Build/RP1A.200720.011) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6312.118 Mobile Safari/537.36 XiaoMi/MiuiBrowser/14.47.1-gn Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 20:09:47 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:47 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:47 volumio volumio[1137]: info: Listing playlists Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 20:09:47 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:48 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:48 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 20:09:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 20:09:53 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:09:53 volumio volumio[1137]: info: Received Get System Info Dec 22 20:09:53 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:09:53 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:09:53 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:09:53 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:09:53 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:09:56 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 20:09:59 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 20:10:04 volumio volumio[1137]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 20:10:04 volumio volumio[1137]: info: Received Get System Version Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 20:10:04 volumio volumio[1137]: info: Received Get System Info Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:10:04 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:04 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:10:07 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:18 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:20 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:10:20 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 20:10:23 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 20:10:25 volumio sudo[6291]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:10:25 volumio sudo[6291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:25 volumio sudo[6291]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:25 volumio sudo[6293]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:10:25 volumio sudo[6293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:25 volumio sudo[6293]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:47 volumio volumio[1137]: info: Received OAUTH Data Dec 22 20:10:47 volumio volumio[1137]: info: Executing Spotify Oauth Login Dec 22 20:10:47 volumio volumio[1137]: info: Saving Spotify Refresh Token Dec 22 20:10:47 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:47 volumio volumio[1137]: SPOTIFY: BQBaSid46VZTD6bh9215PpS_oQFA3h--wef6Gj-TzHSN0k0xDM1yUoA5tCo9mVqUBGP1vCRWNdNQ-XdioiTbG2J9ndCh9L2JzMnvoE6PWtWBjnLnq1DOeZAdgZxVD4S1fbOXhACuvAfxdRCPbcTvGJLm_RbFssb28WRTs5R94zrhf85dLJumTiEV7z_ndQi8ZlcBpt4FYJFJQMXB0A7m9BXl9yp4hkxVVlgzRBBAllwM_v8jXMCnVUAzOKFefkDtcXlcYzkPParmKunCNmwQMCSGAPspGh5tI-PpnNDAIppg-C4Lc3gbGZfN Dec 22 20:10:47 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:47 volumio volumio[1137]: info: New Spotify access token = BQBaSid46VZTD6bh9215PpS_oQFA3h--wef6Gj-TzHSN0k0xDM1yUoA5tCo9mVqUBGP1vCRWNdNQ-XdioiTbG2J9ndCh9L2JzMnvoE6PWtWBjnLnq1DOeZAdgZxVD4S1fbOXhACuvAfxdRCPbcTvGJLm_RbFssb28WRTs5R94zrhf85dLJumTiEV7z_ndQi8ZlcBpt4FYJFJQMXB0A7m9BXl9yp4hkxVVlgzRBBAllwM_v8jXMCnVUAzOKFefkDtcXlcYzkPParmKunCNmwQMCSGAPspGh5tI-PpnNDAIppg-C4Lc3gbGZfN Dec 22 20:10:47 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 20:10:48 volumio volumio[1137]: SPOTIFY: User informations: {"country":"PL","display_name":"Adrian Bogacki","email":"adriangamingteam14@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dhxjgfm6rjcz3225cmw7d2u7ci"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dhxjgfm6rjcz3225cmw7d2u7ci","id":"31dhxjgfm6rjcz3225cmw7d2u7ci","images":[],"product":"free","type":"user","uri":"spotify:user:31dhxjgfm6rjcz3225cmw7d2u7ci"} Dec 22 20:10:48 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:10:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:10:48 volumio volumio[1137]: info: Spotify config file written Dec 22 20:10:48 volumio sudo[6370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:10:48 volumio sudo[6370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:48 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:10:48 volumio systemd[1]: go-librespot-daemon.service: Killing process 6129 (go-librespot) with signal SIGKILL. Dec 22 20:10:48 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:10:48 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:10:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:48 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:10:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:48 volumio sudo[6370]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:48 volumio go-librespot[6372]: go-librespot daemon starting... Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="app state loaded" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="stored credentials not found" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:10:48 volumio sudo[6380]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:10:48 volumio sudo[6380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:48 volumio sudo[6383]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:10:48 volumio sudo[6383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:48 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:48 volumio volumio[1137]: SPOTIFY: BQCPN2st2mnuqfbElotwe25yj5ChynW08NM03yhbKyGH5v6U_05iwZ5rgQj-fFEd7GPON3uy2CYXta3iOREKli7NATXoOjuQN0JbGYejs3xwJCQD2uzWCp4KND57J9tAZBBMrYvJNjOaCkHZr6osSSrDLCjzOIVzHGNIpS83aicKZnHK5W1f6yYqVFTqjAUS_xURakYv0KiOTHMdxHy5DvNWL8uZISz4I8W8FhVqyY_ZL-fWfu6enlzYvj4xx9LRNUg1Cx-EsBkQFh8QZ5NR9KgY9mhXaO4kaYBAQz3DRYoeKwGNEmSTTQ5L Dec 22 20:10:48 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:48 volumio volumio[1137]: info: New Spotify access token = BQCPN2st2mnuqfbElotwe25yj5ChynW08NM03yhbKyGH5v6U_05iwZ5rgQj-fFEd7GPON3uy2CYXta3iOREKli7NATXoOjuQN0JbGYejs3xwJCQD2uzWCp4KND57J9tAZBBMrYvJNjOaCkHZr6osSSrDLCjzOIVzHGNIpS83aicKZnHK5W1f6yYqVFTqjAUS_xURakYv0KiOTHMdxHy5DvNWL8uZISz4I8W8FhVqyY_ZL-fWfu6enlzYvj4xx9LRNUg1Cx-EsBkQFh8QZ5NR9KgY9mhXaO4kaYBAQz3DRYoeKwGNEmSTTQ5L Dec 22 20:10:48 volumio sudo[6380]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:48 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 20:10:48 volumio sudo[6383]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:48 volumio volumio[1137]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.12 UA: Mozilla/5.0 (Linux; Android 11; Redmi Note 8 Pro Build/RP1A.200720.011) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.6312.118 Mobile Safari/537.36 XiaoMi/MiuiBrowser/14.47.1-gn Engine version: 3 Transport: polling Total Clients: 9 Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=info msg="zeroconf server listening on port 38301" Dec 22 20:10:48 volumio volumio[1137]: SPOTIFY: User informations: {"country":"PL","display_name":"Adrian Bogacki","email":"adriangamingteam14@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dhxjgfm6rjcz3225cmw7d2u7ci"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dhxjgfm6rjcz3225cmw7d2u7ci","id":"31dhxjgfm6rjcz3225cmw7d2u7ci","images":[],"product":"free","type":"user","uri":"spotify:user:31dhxjgfm6rjcz3225cmw7d2u7ci"} Dec 22 20:10:48 volumio volumio[1137]: info: Spotify Successfully logged in Dec 22 20:10:48 volumio volumio[1137]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 22 20:10:48 volumio volumio[1137]: info: [1766430648501] CoreMusicLibrary::Adding element Spotify Dec 22 20:10:48 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:10:48 volumio volumio[1137]: Cannot find translation for source Spotify Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="obtained new client token: AAAky38N3asOnUyt3Fw5ZMmcG6RYHZR7VzBK+pFZQy1dQZPjlc85QqGHw28DHp9NMAs5CvT8xjMMCcpu20fB325pw1f5AC7Vio6TYu1gJCirxPtoRQSwlDXG5CyaK0RleWfBu3IPqBRr63NAEvzb3eJrHEInW7BQSg9TBynlisNEjmm3ZjG7AJkBIfvogeWJr6pqr+yMyw6BQhetSelIjxMaT1LsH+KqwBcUYtl+SzDWBYqCx4aCXQ==" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="completed keyexchange" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="completed challenge" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=info msg="authenticated AP" username="31************************ci" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=info msg="authenticated Login5" username="31************************ci" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="stored credentials" username="31************************ci" Dec 22 20:10:48 volumio go-librespot[6375]: time="2025-12-22T20:10:48+01:00" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="dealer connection opened" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=trace msg="starting accesspoint recv loop" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=trace msg="starting dealer recv loop" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=trace msg="received accesspoint ping" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="received connection id: NTQ3MzVhZTEtMGM2...NzMzODA3RDI1QQ==" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=trace msg="received accesspoint pong ack" Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="update volume requested to 65535/65535" Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 20:10:49 volumio volumio[1137]: info: Received Get System Info Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:10:49 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:49 volumio volumio[1137]: info: Listing playlists Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 20:10:49 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 22 20:10:49 volumio go-librespot[6375]: time="2025-12-22T20:10:49+01:00" level=trace msg="emitting websocket event: volume" Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:10:50 volumio volumio[1137]: info: Received Get System Info Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:10:50 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:10:50 volumio volumio[1137]: info: CALLMETHOD: music_service spop logout Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: spop , logout Dec 22 20:10:50 volumio volumio[1137]: info: Deleting Spotify credentials File Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:10:50 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 20:10:51 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:10:51 volumio go-librespot[6375]: time="2025-12-22T20:10:51+01:00" level=debug msg="new websocket client" Dec 22 20:10:51 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:10:51 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 20:10:51 volumio volumio[1137]: info: Received Get System Info Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 20:10:51 volumio volumio[1137]: info: Discovery: Getting this device information Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 20:10:51 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:10:51 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:10:51 volumio volumio[1137]: info: Spotify config file written Dec 22 20:10:51 volumio sudo[6397]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:10:51 volumio sudo[6397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:51 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:10:51 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:10:51 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:10:51 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:51 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:51 volumio go-librespot[6399]: go-librespot daemon starting... Dec 22 20:10:51 volumio sudo[6397]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:51 volumio go-librespot[6400]: time="2025-12-22T20:10:51+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:10:51 volumio go-librespot[6400]: time="2025-12-22T20:10:51+01:00" level=debug msg="no app state found" Dec 22 20:10:51 volumio go-librespot[6400]: time="2025-12-22T20:10:51+01:00" level=debug msg="stored credentials not found" Dec 22 20:10:51 volumio go-librespot[6400]: time="2025-12-22T20:10:51+01:00" level=info msg="generated new device id: 105fea0aa263d40edcd33935a2efde2719b30ef5" Dec 22 20:10:51 volumio go-librespot[6400]: time="2025-12-22T20:10:51+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:10:52 volumio go-librespot[6400]: time="2025-12-22T20:10:52+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 22 20:10:52 volumio go-librespot[6400]: time="2025-12-22T20:10:52+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 22 20:10:52 volumio go-librespot[6400]: time="2025-12-22T20:10:52+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 22 20:10:52 volumio go-librespot[6400]: time="2025-12-22T20:10:52+01:00" level=info msg="zeroconf server listening on port 39425" Dec 22 20:10:52 volumio volumio[1137]: info: CALLMETHOD: music_service spop logout Dec 22 20:10:52 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: spop , logout Dec 22 20:10:52 volumio volumio[1137]: info: Deleting Spotify credentials File Dec 22 20:10:52 volumio volumio[1137]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 22 20:10:52 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 20:10:53 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:10:53 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:10:53 volumio volumio[1137]: info: Spotify config file written Dec 22 20:10:53 volumio sudo[6410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:10:53 volumio sudo[6410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:53 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:10:53 volumio systemd[1]: go-librespot-daemon.service: Killing process 6403 (go-librespot) with signal SIGKILL. Dec 22 20:10:53 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:10:53 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:53 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:53 volumio sudo[6410]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:53 volumio go-librespot[6412]: go-librespot daemon starting... Dec 22 20:10:53 volumio go-librespot[6413]: time="2025-12-22T20:10:53+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:10:53 volumio go-librespot[6413]: time="2025-12-22T20:10:53+01:00" level=debug msg="no app state found" Dec 22 20:10:53 volumio go-librespot[6413]: time="2025-12-22T20:10:53+01:00" level=debug msg="stored credentials not found" Dec 22 20:10:53 volumio go-librespot[6413]: time="2025-12-22T20:10:53+01:00" level=info msg="generated new device id: 47976cd3049a26430b4b01863150eaf7d2289aad" Dec 22 20:10:53 volumio go-librespot[6413]: time="2025-12-22T20:10:53+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=info msg="zeroconf server listening on port 40391" Dec 22 20:10:54 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:10:54 volumio volumio[1137]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 22 20:10:54 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=debug msg="new websocket client" Dec 22 20:10:54 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Dec 22 20:10:54 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:10:54 volumio volumio[1137]: info: Initializing connection to go-librespot Websocket Dec 22 20:10:54 volumio go-librespot[6413]: time="2025-12-22T20:10:54+01:00" level=debug msg="new websocket client" Dec 22 20:10:54 volumio volumio[1137]: info: Connection to go-librespot Websocket established Dec 22 20:10:54 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 22 20:10:56 volumio volumio[1137]: info: Received OAUTH Data Dec 22 20:10:56 volumio volumio[1137]: info: Executing Spotify Oauth Login Dec 22 20:10:56 volumio volumio[1137]: info: Saving Spotify Refresh Token Dec 22 20:10:56 volumio volumio[1137]: info: go-librespot daemon successfully initialized Dec 22 20:10:56 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:56 volumio volumio[1137]: SPOTIFY: BQDVhMv91XMzHGI0Kl-8i6BJ2jV5CtihXlmu3XAmHlNn88pxhDSrcMhj17L3xmkFCnvHjy5M3PybOh9eBtENByzrwRWcJ9rrPnmuUyTyhvJ3kGV3gvW45U3QOuo4-L6hALeIeQJMl0OSfjbe4n2J9O6DIrIiHMJIH72OVRTzsGgns2UfnJEuDLSomxmCP9Ev6rZr6GuveOxLYn-uPRkN275_hZEh_Rmv_YoUEO7aWpo8ph0HwUoJiTimQdE--RMplAkdFcJQ1Z_xdj9UC7A0aotXu71qzM8Qt6gOU2nr27vxxMBAv-_QZo2W Dec 22 20:10:56 volumio volumio[1137]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 22 20:10:56 volumio volumio[1137]: info: New Spotify access token = BQDVhMv91XMzHGI0Kl-8i6BJ2jV5CtihXlmu3XAmHlNn88pxhDSrcMhj17L3xmkFCnvHjy5M3PybOh9eBtENByzrwRWcJ9rrPnmuUyTyhvJ3kGV3gvW45U3QOuo4-L6hALeIeQJMl0OSfjbe4n2J9O6DIrIiHMJIH72OVRTzsGgns2UfnJEuDLSomxmCP9Ev6rZr6GuveOxLYn-uPRkN275_hZEh_Rmv_YoUEO7aWpo8ph0HwUoJiTimQdE--RMplAkdFcJQ1Z_xdj9UC7A0aotXu71qzM8Qt6gOU2nr27vxxMBAv-_QZo2W Dec 22 20:10:56 volumio volumio[1137]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 22 20:10:57 volumio volumio[1137]: SPOTIFY: User informations: {"country":"PL","display_name":"Adrian Bogacki","email":"adriangamingteam14@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31dhxjgfm6rjcz3225cmw7d2u7ci"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31dhxjgfm6rjcz3225cmw7d2u7ci","id":"31dhxjgfm6rjcz3225cmw7d2u7ci","images":[],"product":"free","type":"user","uri":"spotify:user:31dhxjgfm6rjcz3225cmw7d2u7ci"} Dec 22 20:10:57 volumio volumio[1137]: info: Creating Spotify config file Dec 22 20:10:57 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 20:10:57 volumio volumio[1137]: info: Spotify config file written Dec 22 20:10:57 volumio sudo[6426]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 22 20:10:57 volumio sudo[6426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:57 volumio sudo[6428]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 20:10:57 volumio sudo[6428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:57 volumio sudo[6428]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:57 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 22 20:10:57 volumio sudo[6430]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 20:10:57 volumio sudo[6430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:57 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 22 20:10:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:57 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:10:57 volumio volumio[1137]: info: Connection to go-librespot Websocket closed Dec 22 20:10:57 volumio sudo[6430]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:57 volumio volumio[1137]: info: Getting Spotify volume Dec 22 20:10:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 22 20:10:57 volumio sudo[6426]: pam_unix(sudo:session): session closed for user root Dec 22 20:10:57 volumio go-librespot[6435]: go-librespot daemon starting... Dec 22 20:10:57 volumio volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 20:10:57 volumio volumio[1137]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 22 20:10:57 volumio volumio[1137]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 22 20:10:57 volumio volumio[1137]: errno: -111, Dec 22 20:10:57 volumio volumio[1137]: code: 'ECONNREFUSED', Dec 22 20:10:57 volumio volumio[1137]: syscall: 'connect', Dec 22 20:10:57 volumio volumio[1137]: address: '127.0.0.1', Dec 22 20:10:57 volumio volumio[1137]: port: 9879, Dec 22 20:10:57 volumio volumio[1137]: response: undefined Dec 22 20:10:57 volumio volumio[1137]: } Dec 22 20:10:57 volumio volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=info msg="running go-librespot 0.4.0" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="app state loaded" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="stored credentials not found" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=info msg="zeroconf server listening on port 43227" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="obtained new client token: AAAnc0sIhUAECnNXpA2s5M7qIGzn8emQ7J70uJlJBfjOr7Z/WS+eW3R70ZrQWyto0Xl5cvkDMEpX09tAtWFEO96sDuOFTv0c7UZBbRlzj+cYxkxC3pXNOAIaezCWwieyF1rA0ObOj1vPJzYF1u9WRM+mTlTEs9Nz7nIq+XoxmnHYtY9wv8mpUyv42+wobwHXEwbg3gD+Soqnu9pIJXOIbPr7ZRZpFE8W67aR3c5fVql6f++MyVRyRg==" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 22 20:10:57 volumio sudo[6459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 20:09' Dec 22 20:10:57 volumio sudo[6459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="completed keyexchange" Dec 22 20:10:57 volumio go-librespot[6437]: time="2025-12-22T20:10:57+01:00" level=debug msg="completed challenge" PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"