Dec 19 22:15:00 volumio-adriana bash[4921]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:00 volumio-adriana bash[4921]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:00 volumio-adriana bash[4927]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:00 volumio-adriana bash[4927]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:00 volumio-adriana bash[4934]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:00 volumio-adriana bash[4934]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:00 volumio-adriana bash[4931]: cset: **> mount of cpuset filesystem failed, do you have permission? Dec 19 22:15:00 volumio-adriana systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 19 22:15:01 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:01Z" level=trace msg="sent dealer ping" Dec 19 22:15:01 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:01Z" level=trace msg="received dealer pong" Dec 19 22:15:02 volumio-adriana sudo[4946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 22:15:02 volumio-adriana sudo[4946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:15:02 volumio-adriana systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 22:15:02 volumio-adriana systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 22:15:02 volumio-adriana systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 22:15:02 volumio-adriana systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 22:15:02 volumio-adriana sudo[4946]: pam_unix(sudo:session): session closed for user root Dec 19 22:15:02 volumio-adriana mpd_monitor.sh[4948]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 22:15:02 volumio-adriana volumio[1120]: info: Successfully started MPD Monitor Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Dec 19 22:15:06 volumio-adriana sudo[4956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 22:15:06 volumio-adriana sudo[4956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:15:06 volumio-adriana sudo[4956]: pam_unix(sudo:session): session closed for user root Dec 19 22:15:06 volumio-adriana sudo[4958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 22:15:06 volumio-adriana volumio[1120]: info: MPD Permissions set Dec 19 22:15:06 volumio-adriana sudo[4958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 19 22:15:06 volumio-adriana systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 19 22:15:06 volumio-adriana systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 19 22:15:06 volumio-adriana systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 19 22:15:06 volumio-adriana systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 19 22:15:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:15:06 volumio-adriana systemd[1]: mpd.service: Deactivated successfully. Dec 19 22:15:06 volumio-adriana systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 22:15:06 volumio-adriana systemd[1]: mpd.service: Consumed 1.335s CPU time. Dec 19 22:15:06 volumio-adriana systemd[1]: mpd.socket: Deactivated successfully. Dec 19 22:15:06 volumio-adriana systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 22:15:06 volumio-adriana systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 22:15:06 volumio-adriana systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 22:15:06 volumio-adriana systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 22:15:06 volumio-adriana sudo[4970]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 22:15:06 volumio-adriana sudo[4970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 22:15:06 volumio-adriana sudo[4970]: pam_unix(sudo:session): session closed for user root Dec 19 22:15:07 volumio-adriana mpd[4972]: 2025-12-19T22:15:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 22:15:07 volumio-adriana systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 22:15:07 volumio-adriana sudo[4958]: pam_unix(sudo:session): session closed for user root Dec 19 22:15:07 volumio-adriana volumio[1120]: error: updateQueue error: null Dec 19 22:15:07 volumio-adriana systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 19 22:15:07 volumio-adriana bash[5000]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:07 volumio-adriana bash[5000]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:08 volumio-adriana bash[5007]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:08 volumio-adriana bash[5007]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:08 volumio-adriana bash[5013]: mount: /cpusets: unknown filesystem type 'cpuset'. Dec 19 22:15:08 volumio-adriana bash[5013]: dmesg(1) may have more information after failed mount system call. Dec 19 22:15:08 volumio-adriana bash[5011]: cset: **> mount of cpuset filesystem failed, do you have permission? Dec 19 22:15:08 volumio-adriana systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 19 22:15:10 volumio-adriana sudo[5025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 22:15:10 volumio-adriana sudo[5025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:15:10 volumio-adriana systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 22:15:10 volumio-adriana systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 22:15:10 volumio-adriana systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 22:15:10 volumio-adriana systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 22:15:10 volumio-adriana sudo[5025]: pam_unix(sudo:session): session closed for user root Dec 19 22:15:10 volumio-adriana mpd_monitor.sh[5027]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 22:15:10 volumio-adriana volumio[1120]: info: Successfully started MPD Monitor Dec 19 22:15:23 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:15:23 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioPlay Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CoreStateMachine::play index undefined Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CoreStateMachine::startPlaybackTimer Dec 19 22:15:25 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:25 volumio-adriana volumio[1120]: info: [1766182525583] ControllerSpotify::clearAddPlayTrack Dec 19 22:15:25 volumio-adriana volumio[1120]: info: Sending Spotify command with payload to local API: /player/play Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=debug msg="resolved context of track" uri="spotify:track:5y0CCj9ENhM4G3gebGlnzb" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5y0CCj9ENhM4G3gebGlnzb" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5y0CCj9ENhM4G3gebGlnzb" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=trace msg="emitting websocket event: will_play" Dec 19 22:15:25 volumio-adriana volumio[1120]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:5y0CCj9ENhM4G3gebGlnzb","uri":"spotify:track:5y0CCj9ENhM4G3gebGlnzb","play_origin":"go-librespot"}} Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=debug msg="selected format OGG_VORBIS_320 (d271f5801e1bba06b6820aa93fa40d3386e23d78)" uri="spotify:track:5y0CCj9ENhM4G3gebGlnzb" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=debug msg="requested aes key for file d271f5801e1bba06b6820aa93fa40d3386e23d78, gid: 5y0CCj9ENhM4G3gebGlnzb" Dec 19 22:15:25 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:25Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:5y0CCj9ENhM4G3gebGlnzb: failed retrieving audio key: failed retrieving aes key with code 1" Dec 19 22:15:25 volumio-adriana volumio[1120]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 22:15:31 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:31Z" level=trace msg="sent dealer ping" Dec 19 22:15:31 volumio-adriana go-librespot[1847]: time="2025-12-19T22:15:31Z" level=trace msg="received dealer pong" Dec 19 22:15:33 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 22:15:33 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 19 22:15:36 volumio-adriana volumio[1120]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 22:15:54 volumio-adriana volumio[1120]: 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: 7 Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetQueue Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreStateMachine::getQueue Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CorePlayQueue::getQueue Dec 19 22:15:55 volumio-adriana volumio[1120]: info: Listing playlists Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 22:15:55 volumio-adriana volumio[1120]: info: Received Get System Info Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 22:15:55 volumio-adriana volumio[1120]: info: Discovery: Getting this device information Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:15:55 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 19 22:16:01 volumio-adriana go-librespot[1847]: time="2025-12-19T22:16:01Z" level=trace msg="sent dealer ping" Dec 19 22:16:01 volumio-adriana go-librespot[1847]: time="2025-12-19T22:16:01Z" level=trace msg="received dealer pong" Dec 19 22:16:02 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:02 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:02 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:02 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:02 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:02 volumio-adriana sudo[5174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:02 volumio-adriana sudo[5174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:02 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:02 volumio-adriana systemd[1]: go-librespot-daemon.service: Killing process 1871 (go-librespot) with signal SIGKILL. Dec 19 22:16:02 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:02 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:02 volumio-adriana systemd[1]: go-librespot-daemon.service: Consumed 1.026s CPU time. Dec 19 22:16:02 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket closed Dec 19 22:16:02 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:02 volumio-adriana go-librespot[5176]: go-librespot daemon starting... Dec 19 22:16:02 volumio-adriana sudo[5174]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:02 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:02Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:02 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:02Z" level=debug msg="app state loaded" Dec 19 22:16:02 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:02Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" 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 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" 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 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" 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 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=info msg="zeroconf server listening on port 33445" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="obtained new client token: AACy5qIZogVwAN9nU8+Kh5DNP2I/nDZj/IZsJGvAeqrsR+9gXaJnhHpZTgg8FOIga5jU0i6dtxCnOvj6yoxqeZdorjRn9iD61TDgyg+Pla0BepX/DZRAxPWWLcPBGjq9ExdJGeXG3F9WXvBVz4JOqYbjRc9OrEJ7TSDesxXPKCt1f86WR7HmQX8OyerhYmpHQJiMt/vUoYPkspuRMY5ddBDx87W08L9BJb6CCde2azpdqv0vYi0=" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="completed keyexchange" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="completed challenge" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=info msg="authenticated AP" username="31************************ci" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=info msg="authenticated Login5" username="31************************ci" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 19 22:16:03 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 19 22:16:03 volumio-adriana volumio[1120]: info: In handleBrowseUri, curUri=spotify Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="dealer connection opened" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=trace msg="starting accesspoint recv loop" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=trace msg="starting dealer recv loop" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=trace msg="received accesspoint ping" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="received connection id: ZTEyM2Q4NTctZDM5...NTBDNkQyNEFBRQ==" Dec 19 22:16:03 volumio-adriana volumio[1120]: info: Preload queue cleared Dec 19 22:16:03 volumio-adriana volumio[1120]: info: Preload queue cleared Dec 19 22:16:03 volumio-adriana volumio[1120]: info: Preload queue cleared Dec 19 22:16:03 volumio-adriana volumio[1120]: info: Preload queue cleared Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=trace msg="received accesspoint pong ack" Dec 19 22:16:03 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:03Z" level=debug msg="put connect state because NEW_DEVICE" Dec 19 22:16:05 volumio-adriana volumio[1120]: info: Initializing connection to go-librespot Websocket Dec 19 22:16:05 volumio-adriana volumio[1120]: info: go-librespot daemon successfully initialized Dec 19 22:16:05 volumio-adriana go-librespot[5177]: time="2025-12-19T22:16:05Z" level=debug msg="new websocket client" Dec 19 22:16:05 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket established Dec 19 22:16:06 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 19 22:16:06 volumio-adriana volumio[1120]: info: In handleBrowseUri, curUri=spotify/mytoptracks Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preload queue cleared Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:5y0CCj9ENhM4G3gebGlnzb Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0P3F6xMn3R1Ja8hjJ1HBTO Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:15JOpNxF5pZOW8VycDxS86 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:75BqGmJpXL4oVrbdEJAaVJ Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0BAl5xQs7JqXmxx0NKSQng Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:1DWt41ow6acANYlPqm9OW1 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3205ZtwMnzMBtr7GQZ1IbH Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3IXuxsI9ltJw3WpRzVtSPL Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:6esBajH4pfY0i61Vr53QWR Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0NJdtoQ3RX5ckBjJlNXhlP Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0Tml3iA3nAg4wMTcYQ9rzC Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0iBdjtSnf51q1M7ywAAxNg Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0pMRvQSP3hO7q4dvviZFf5 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:1RjAaXg3VItG4QEmJ6lOR9 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:2QZu0T4ITjcnyTAMqyWkA8 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7LJWAehs8QG5x6NuDQ4PFQ Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7eBnyfgpicRCTgGvlwJoMS Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:27ZEr3LW65VzLeBHuvtkdC Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:2vXSHAPCNpWZ7DNFDkEDMz Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7EsWHWICEHCujCLeHrnnQ8 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7qJNcTsl3wGAj25OEHoEma Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:1A0KIyruOj6BvhRRyAKtag Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:6eF3F1VZQCb2jOUP8kDIce Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3gvJeMSwp2UUnibGpmdIhh Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:09bXpqLbPGZum6I4lDcnMX Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0MTTFee7cTSd46njlPKBZe Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0QDGplY5KALFQQA2w8bdo9 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:1iuWJYOPwu4d1fuQLXdO0Z Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:2Bvmq94ifFUXQmaFPR5Vis Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:2lszjkyKpER3mEFPQQayZR Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:380IZtWd0kGFzRt2Z28Z7A Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3CgWmnnox6kF0NvJpZWSv8 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3FbJDG6nRIMtD6USO26H6i Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3LvLlSFVzHIzdFfrCJhQxj Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:3fIljeEk8Ua70Rq1DYNCpV Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:5TC6AHCa6oJVMvZaHT3zzK Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:5Wrl4uc9SjC8ZnAimiMtys Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:5gpBf3DQRxhFVUhQaHzXlv Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:5zUYnOtHEjzin6C0bqedXP Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:6ROe4DpkMjRmklQnv0p0KU Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:6xGNnyu66yXZhjenvznd6f Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7d70HcsqpXC2bTb69PIW7e Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:7qk30DAwdoTM3gYzzqUd2K Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:4xihegwJ7449BTqQDUjwIx Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0ZwFAzKgF9ehHKnZeI1lD2 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:0nPQJxDQZOxkgnuLs4jysq Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:159HN1lk9uH0g01tAJH93R Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Preloading song: spotify:track:1DpA5Bg9neeEJJnKInmYEs Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:5y0CCj9ENhM4G3gebGlnzb in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:5y0CCj9ENhM4G3gebGlnzb Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0P3F6xMn3R1Ja8hjJ1HBTO in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0P3F6xMn3R1Ja8hjJ1HBTO Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:15JOpNxF5pZOW8VycDxS86 in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:15JOpNxF5pZOW8VycDxS86 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:75BqGmJpXL4oVrbdEJAaVJ in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:75BqGmJpXL4oVrbdEJAaVJ Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0BAl5xQs7JqXmxx0NKSQng in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0BAl5xQs7JqXmxx0NKSQng Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:15JOpNxF5pZOW8VycDxS86","service":"spop","name":"WESELE W DUBAJU","artist":"Daj To Głośniej","album":"WESELE W DUBAJU","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b273738e0b488bfe288c859b04cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5y0CCj9ENhM4G3gebGlnzb","service":"spop","name":"MAMA POWTARZAŁA","artist":"Sobel","album":"W ZWIĄZKU Z MUZYKĄ","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b2735f4773d2376217ea2764c432","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:1DWt41ow6acANYlPqm9OW1 in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:1DWt41ow6acANYlPqm9OW1 Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75BqGmJpXL4oVrbdEJAaVJ","service":"spop","name":"Skuter","artist":"Sidney Polak","album":"Cyfrowy Styl Zycia","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273db700d7964b888ebf503d68e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3205ZtwMnzMBtr7GQZ1IbH in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3205ZtwMnzMBtr7GQZ1IbH Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0P3F6xMn3R1Ja8hjJ1HBTO","service":"spop","name":"Džanum","artist":"Teya Dora","album":"Džanum","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273713d8d17badf5fa53383d271","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3IXuxsI9ltJw3WpRzVtSPL in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3IXuxsI9ltJw3WpRzVtSPL Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0BAl5xQs7JqXmxx0NKSQng","service":"spop","name":"Mama Ostrzegała","artist":"Daj To Głośniej","album":"Folk Disco","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b27309125568c844b57205a607c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:6esBajH4pfY0i61Vr53QWR in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:6esBajH4pfY0i61Vr53QWR Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3205ZtwMnzMBtr7GQZ1IbH","service":"spop","name":"Origami","artist":"Ronnie Ferrari","album":"Origami","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b27364e8acda9e4867f5352cbed6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0NJdtoQ3RX5ckBjJlNXhlP in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0NJdtoQ3RX5ckBjJlNXhlP Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0Tml3iA3nAg4wMTcYQ9rzC in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0Tml3iA3nAg4wMTcYQ9rzC Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3IXuxsI9ltJw3WpRzVtSPL","service":"spop","name":"Różowe Diamenty","artist":"Young Leosia","album":"Różowe Diamenty","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b273e37eabea6d96fba3601b561c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6esBajH4pfY0i61Vr53QWR","service":"spop","name":"On Me","artist":"MODELKI","album":"On Me","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b273edd81bc6dbe39fd0bfdb6f15","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1DWt41ow6acANYlPqm9OW1","service":"spop","name":"Ona Zaskoczona","artist":"Daj To Głośniej","album":"Ona Zaskoczona","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b27387863404e0b7d5cca8b8f428","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0NJdtoQ3RX5ckBjJlNXhlP","service":"spop","name":"La Isla Bonita","artist":"Madonna","album":"True Blue","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab2411303d48498dc1d8c137","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0iBdjtSnf51q1M7ywAAxNg in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0iBdjtSnf51q1M7ywAAxNg Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0pMRvQSP3hO7q4dvviZFf5 in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0pMRvQSP3hO7q4dvviZFf5 Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0iBdjtSnf51q1M7ywAAxNg","service":"spop","name":"Far over the Misty Mountains Cold","artist":"Geoff Castellucci","album":"Far over the Misty Mountains Cold","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b2737911b1f52d847dbd541a467e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:1RjAaXg3VItG4QEmJ6lOR9 in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:1RjAaXg3VItG4QEmJ6lOR9 Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0pMRvQSP3hO7q4dvviZFf5","service":"spop","name":"SOBOTA WIECZÓR","artist":"Young Leosia","album":"Atmosfera","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b27389296ead561af6738a737792","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:2QZu0T4ITjcnyTAMqyWkA8 in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:2QZu0T4ITjcnyTAMqyWkA8 Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7LJWAehs8QG5x6NuDQ4PFQ in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7LJWAehs8QG5x6NuDQ4PFQ Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Tml3iA3nAg4wMTcYQ9rzC","service":"spop","name":"In Our Memories","artist":"Abject","album":"In Our Memories (Wizeguy Remix)","type":"song","duration":353,"albumart":"https://i.scdn.co/image/ab67616d0000b2738256289a9cf914417eee8cb0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QZu0T4ITjcnyTAMqyWkA8","service":"spop","name":"Ze Wsi Dziewczyna","artist":"Daj To Głośniej","album":"Ze Wsi Dziewczyna","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb9c2ad836aaa33de4a24142","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7eBnyfgpicRCTgGvlwJoMS in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7eBnyfgpicRCTgGvlwJoMS Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1RjAaXg3VItG4QEmJ6lOR9","service":"spop","name":"VIXIARSKI BALET","artist":"Kimik","album":"VIXIARSKI BALET","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b2730d4f2c37a0b75a22dee07182","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:07 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:27ZEr3LW65VzLeBHuvtkdC in service spop Dec 19 22:16:07 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:27ZEr3LW65VzLeBHuvtkdC Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:2vXSHAPCNpWZ7DNFDkEDMz in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:2vXSHAPCNpWZ7DNFDkEDMz Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7LJWAehs8QG5x6NuDQ4PFQ","service":"spop","name":"Zbuntowany Anioł (Radio Edit)","artist":"Łobuzy","album":"Zbuntowany Anioł (Radio Edit)","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cab96501d5d313949396e50","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7EsWHWICEHCujCLeHrnnQ8 in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7EsWHWICEHCujCLeHrnnQ8 Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7qJNcTsl3wGAj25OEHoEma in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7qJNcTsl3wGAj25OEHoEma Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:27ZEr3LW65VzLeBHuvtkdC","service":"spop","name":"JEDEN TANIEC JEDNA NOC","artist":"MiłyPan","album":"JEDEN TANIEC JEDNA NOC","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6ea7bb48d9c81979588fa59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:1A0KIyruOj6BvhRRyAKtag in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:1A0KIyruOj6BvhRRyAKtag Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7eBnyfgpicRCTgGvlwJoMS","service":"spop","name":"Santa Cruz","artist":"Ekipa","album":"Santa Cruz","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b2731310851ffa2b808d2ba2e446","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:6eF3F1VZQCb2jOUP8kDIce in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:6eF3F1VZQCb2jOUP8kDIce Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7EsWHWICEHCujCLeHrnnQ8","service":"spop","name":"Gdy Rodzice Pójdą Spać","artist":"Daj To Głośniej","album":"Folk Disco","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b27309125568c844b57205a607c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2vXSHAPCNpWZ7DNFDkEDMz","service":"spop","name":"Meet you at the Graveyard","artist":"Cleffy","album":"Clean Sheets, dirty walls","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b03a3f3d8918856e1519645","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3gvJeMSwp2UUnibGpmdIhh in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3gvJeMSwp2UUnibGpmdIhh Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7qJNcTsl3wGAj25OEHoEma","service":"spop","name":"Ej Agatka","artist":"Young Leosia","album":"Ej Agatka","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b273101e62a5d16743773394d664","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1A0KIyruOj6BvhRRyAKtag","service":"spop","name":"Małolatki","artist":"MiłyPan","album":"Discopolot","type":"song","duration":218,"albumart":"https://i.scdn.co/image/ab67616d0000b273fd68011b2bf5c9dbff6a6bea","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:09bXpqLbPGZum6I4lDcnMX in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:09bXpqLbPGZum6I4lDcnMX Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6eF3F1VZQCb2jOUP8kDIce","service":"spop","name":"Uciekaj dziewczyno","artist":"Daj To Głośniej","album":"Folk Disco","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b27309125568c844b57205a607c8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0MTTFee7cTSd46njlPKBZe in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0MTTFee7cTSd46njlPKBZe Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3gvJeMSwp2UUnibGpmdIhh","service":"spop","name":"Ile Dać Mam","artist":"MODELKI","album":"Ile Dać Mam","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273e376c1766d711d8470e6a541","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0QDGplY5KALFQQA2w8bdo9 in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0QDGplY5KALFQQA2w8bdo9 Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:1iuWJYOPwu4d1fuQLXdO0Z in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:1iuWJYOPwu4d1fuQLXdO0Z Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:09bXpqLbPGZum6I4lDcnMX","service":"spop","name":"Malibu Barbie","artist":"club2020","album":"club2020","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273d2b86b3469b70a6945fdaa7d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:08 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0MTTFee7cTSd46njlPKBZe","service":"spop","name":"Przester","artist":"Young Leosia","album":"PG$","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273f08323f88dfc2f82f7ca8034","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:2Bvmq94ifFUXQmaFPR5Vis in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:2Bvmq94ifFUXQmaFPR5Vis Dec 19 22:16:08 volumio-adriana sudo[5207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:08 volumio-adriana sudo[5207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:08 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:08 volumio-adriana systemd[1]: go-librespot-daemon.service: Killing process 5178 (go-librespot) with signal SIGKILL. Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:2lszjkyKpER3mEFPQQayZR in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:2lszjkyKpER3mEFPQQayZR Dec 19 22:16:08 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:08 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket closed Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1iuWJYOPwu4d1fuQLXdO0Z","service":"spop","name":"NIE MA CIĘ","artist":"Fukaj","album":"PRELUDIUM","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b27341773870db0c908566fb6b3a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0QDGplY5KALFQQA2w8bdo9","service":"spop","name":"Toksyna","artist":"Kwestia 07","album":"Największe hity","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273b47883d273dc7e90b60ccf1f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:08 volumio-adriana sudo[5207]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:08 volumio-adriana go-librespot[5209]: go-librespot daemon starting... Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="app state loaded" Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Bvmq94ifFUXQmaFPR5Vis","service":"spop","name":"PG$","artist":"Young Leosia","album":"PG$","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273f08323f88dfc2f82f7ca8034","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:380IZtWd0kGFzRt2Z28Z7A in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:380IZtWd0kGFzRt2Z28Z7A Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3CgWmnnox6kF0NvJpZWSv8 in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3CgWmnnox6kF0NvJpZWSv8 Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3FbJDG6nRIMtD6USO26H6i in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3FbJDG6nRIMtD6USO26H6i Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" 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 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" 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 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" 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 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=info msg="zeroconf server listening on port 37767" Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2lszjkyKpER3mEFPQQayZR","service":"spop","name":"Uno Momento","artist":"Majki","album":"Uno Momento","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b2731760b49d253988a1e518aff9","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:380IZtWd0kGFzRt2Z28Z7A","service":"spop","name":"PLN","artist":"Young Leosia","album":"PLN","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273828220833453824b694b75bb","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3LvLlSFVzHIzdFfrCJhQxj in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3LvLlSFVzHIzdFfrCJhQxj Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="obtained new client token: AAAXfwdkz7hp9ugsenhAmLAmpRieWR7/GPaYtqOo9kFEkOoAXpnAK/va4KrM1KsVilkp2jLkV4K28toiZ2ZfEkst7BqIqtr6rSYSiODZB+sBZUGj0jLi4FaEJiBq87kW0uT+MxTbJ7VVbUsVrZixYowlucSBFQQ53f0sIWI0gQ1y37t+mI3VAFVWlFzA9WF3xlSenXQ4t+/6XlVIRkvThg9uvG4w6mZCbS3pHM2dZCCD9Eto9r1RMw==" Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:3fIljeEk8Ua70Rq1DYNCpV in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:3fIljeEk8Ua70Rq1DYNCpV Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="completed keyexchange" Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="completed challenge" Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:5TC6AHCa6oJVMvZaHT3zzK in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:5TC6AHCa6oJVMvZaHT3zzK Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3CgWmnnox6kF0NvJpZWSv8","service":"spop","name":"Te numery","artist":"Young Leosia","album":"PG$","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273f08323f88dfc2f82f7ca8034","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=info msg="authenticated AP" username="31************************ci" Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3FbJDG6nRIMtD6USO26H6i","service":"spop","name":"Miód Malina","artist":"Mig","album":"Wymarzona","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273ecdcce7c85a442fa83fbe946","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:5Wrl4uc9SjC8ZnAimiMtys in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:5Wrl4uc9SjC8ZnAimiMtys Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Initializing connection to go-librespot Websocket Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=info msg="authenticated Login5" username="31************************ci" Dec 19 22:16:08 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:08Z" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Getting Spotify volume Dec 19 22:16:08 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:5gpBf3DQRxhFVUhQaHzXlv in service spop Dec 19 22:16:08 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:5gpBf3DQRxhFVUhQaHzXlv Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="new websocket client" Dec 19 22:16:09 volumio-adriana volumio[1120]: 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 19 22:16:09 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket established Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:5zUYnOtHEjzin6C0bqedXP in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:5zUYnOtHEjzin6C0bqedXP Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="dealer connection opened" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=trace msg="starting accesspoint recv loop" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=trace msg="starting dealer recv loop" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=trace msg="received accesspoint ping" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="received connection id: M2NlYjk3ZDItMzYx...REY0OTE3MDk0MA==" Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Wrl4uc9SjC8ZnAimiMtys","service":"spop","name":"Przekorny Los","artist":"Akcent","album":"Przekorny Los","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b2737fb60d0c98027f878fee661b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Spotify volume: 100 Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:6ROe4DpkMjRmklQnv0p0KU in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:6ROe4DpkMjRmklQnv0p0KU Dec 19 22:16:09 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:16:09 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=trace msg="received accesspoint pong ack" Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3LvLlSFVzHIzdFfrCJhQxj","service":"spop","name":"Piosenka o białym misiu","artist":"Put-In","album":"Piosenka o białym misiu","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273eed756e5e611921321c35b1e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5TC6AHCa6oJVMvZaHT3zzK","service":"spop","name":"Ochotę Mam","artist":"Daj To Głośniej","album":"Folk Disco","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b27309125568c844b57205a607c8","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:6xGNnyu66yXZhjenvznd6f in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:6xGNnyu66yXZhjenvznd6f Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fIljeEk8Ua70Rq1DYNCpV","service":"spop","name":"zostań, proszę","artist":"kuqe 2115","album":"nareszcie w domu","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b273dfd54dd4db450e7db97d4881","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5gpBf3DQRxhFVUhQaHzXlv","service":"spop","name":"Zwariowana noc","artist":"Daj To Głośniej","album":"Zwariowana noc","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b273c73f587d123f9e74c8c25676","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7d70HcsqpXC2bTb69PIW7e in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7d70HcsqpXC2bTb69PIW7e Dec 19 22:16:09 volumio-adriana go-librespot[5210]: time="2025-12-19T22:16:09Z" level=debug msg="put connect state because NEW_DEVICE" Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:7qk30DAwdoTM3gYzzqUd2K in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:7qk30DAwdoTM3gYzzqUd2K Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ROe4DpkMjRmklQnv0p0KU","service":"spop","name":"Nie Daj Życiu Się","artist":"After Party","album":"Nie Daj Życiu Się","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b2731b1711148ad3b71bbd01863d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:4xihegwJ7449BTqQDUjwIx in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:4xihegwJ7449BTqQDUjwIx Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xGNnyu66yXZhjenvznd6f","service":"spop","name":"Zapalimy blanta","artist":"Kumi","album":"Zapalimy blanta","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4e3a811775b1c32c18c192d","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0ZwFAzKgF9ehHKnZeI1lD2 in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0ZwFAzKgF9ehHKnZeI1lD2 Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7d70HcsqpXC2bTb69PIW7e","service":"spop","name":"Atmosfera","artist":"Young Leosia","album":"Atmosfera","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b27389296ead561af6738a737792","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:0nPQJxDQZOxkgnuLs4jysq in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:0nPQJxDQZOxkgnuLs4jysq Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5zUYnOtHEjzin6C0bqedXP","service":"spop","name":"Noc Aniołów","artist":"Young Leosia","album":"Atmosfera","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b27389296ead561af6738a737792","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7qk30DAwdoTM3gYzzqUd2K","service":"spop","name":"Przestrzeń","artist":"Young Leosia","album":"Atmosfera","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b27389296ead561af6738a737792","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:159HN1lk9uH0g01tAJH93R in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:159HN1lk9uH0g01tAJH93R Dec 19 22:16:09 volumio-adriana volumio[1120]: info: Exploding uri spotify:track:1DpA5Bg9neeEJJnKInmYEs in service spop Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: EXPLODING URI:spotify:track:1DpA5Bg9neeEJJnKInmYEs Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xihegwJ7449BTqQDUjwIx","service":"spop","name":"Zerujemy 07","artist":"Dr. SWAG","album":"Zerujemy 07","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b273ef5f17b12e692def71e6b96c","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ZwFAzKgF9ehHKnZeI1lD2","service":"spop","name":"Big Bad John","artist":"Geoff Castellucci","album":"Big Bad John","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273e0ab31ab0aa1c868bc3f193a","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0nPQJxDQZOxkgnuLs4jysq","service":"spop","name":"Ona czuje we mnie piniądz","artist":"Łobuzy","album":"Ona czuje we mnie piniądz","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b2736346f42bbbe045c72a9f759c","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:159HN1lk9uH0g01tAJH93R","service":"spop","name":"Królowa","artist":"MiłyPan","album":"Królowa","type":"song","duration":245,"albumart":"https://i.scdn.co/image/ab67616d0000b273c204101f09dafa2f398b4525","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:09 volumio-adriana volumio[1120]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1DpA5Bg9neeEJJnKInmYEs","service":"spop","name":"Nocy mało","artist":"Masters","album":"7","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273867178adca8fcef290d1aefe","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 22:16:11 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:11 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:11 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:11 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:11 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:11 volumio-adriana sudo[5225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:11 volumio-adriana sudo[5225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:11 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:11 volumio-adriana systemd[1]: go-librespot-daemon.service: Killing process 5216 (go-librespot) with signal SIGKILL. Dec 19 22:16:11 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket closed Dec 19 22:16:11 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:11 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:11 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:11 volumio-adriana go-librespot[5227]: go-librespot daemon starting... Dec 19 22:16:11 volumio-adriana sudo[5225]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="app state loaded" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:11 volumio-adriana volumio[1120]: info: go-librespot daemon successfully initialized Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" 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 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" 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 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" 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 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=info msg="zeroconf server listening on port 38523" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="obtained new client token: AABN7wQsB3ej+cg1sT2JZf2WtbHTWwibaURbwtEY8wFNSPAfKRkp372xGoBhrudzQMHQ8/Eqi5AC1/hYkFzMZKwqGoqzfXQNiQyc4q9jNaYJlGRAawluutlqhViLFwJrxsdiaSDdFN0W1J0+T//ozPf7tbzP7VDOy5D3dQ9lXT90xddKEhmzNqP6gw9/fDFqc8CuG8rj12yjPI2YK9FwPPp9WZDdps6Yo7ORoL0MlCSHjs9zkpBmnA==" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="completed keyexchange" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="completed challenge" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=info msg="authenticated AP" username="31************************ci" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=info msg="authenticated Login5" username="31************************ci" Dec 19 22:16:11 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:11Z" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 19 22:16:12 volumio-adriana volumio[1120]: info: Getting Spotify volume Dec 19 22:16:12 volumio-adriana volumio[1120]: 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 19 22:16:12 volumio-adriana volumio[1120]: info: CoreCommandRouter::volumioGetState Dec 19 22:16:12 volumio-adriana volumio[1120]: info: CorePlayQueue::getTrack 0 Dec 19 22:16:12 volumio-adriana volumio[1120]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="dealer connection opened" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=trace msg="starting accesspoint recv loop" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=trace msg="starting dealer recv loop" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=trace msg="received accesspoint ping" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="received connection id: ODJlZjE5OTYtNjM4...RTU3QzczQTExNA==" Dec 19 22:16:12 volumio-adriana volumio[1120]: info: Spotify volume: 100 Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=trace msg="received accesspoint pong ack" Dec 19 22:16:12 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:12Z" level=debug msg="put connect state because NEW_DEVICE" Dec 19 22:16:14 volumio-adriana volumio[1120]: info: Initializing connection to go-librespot Websocket Dec 19 22:16:14 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:14Z" level=debug msg="new websocket client" Dec 19 22:16:14 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket established Dec 19 22:16:14 volumio-adriana volumio[1120]: info: go-librespot daemon successfully initialized Dec 19 22:16:14 volumio-adriana volumio[1120]: info: Initializing connection to go-librespot Websocket Dec 19 22:16:14 volumio-adriana go-librespot[5228]: time="2025-12-19T22:16:14Z" level=debug msg="new websocket client" Dec 19 22:16:14 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket established Dec 19 22:16:15 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:15 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:15 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:15 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:15 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:15 volumio-adriana sudo[5240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:15 volumio-adriana sudo[5240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:15 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:15 volumio-adriana systemd[1]: go-librespot-daemon.service: Killing process 5232 (go-librespot) with signal SIGKILL. Dec 19 22:16:15 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket closed Dec 19 22:16:15 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:15 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:15 volumio-adriana volumio[1120]: info: Connection to go-librespot Websocket closed Dec 19 22:16:15 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:15 volumio-adriana sudo[5240]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:15 volumio-adriana go-librespot[5242]: go-librespot daemon starting... Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=debug msg="app state loaded" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" 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 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" 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 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" 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 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=info msg="zeroconf server listening on port 46289" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=debug msg="obtained new client token: AAAWSEHlkMpQHDKKhIgAc+HxavUhuLawVHm3Ib/w3qaidSs0G6VyRtW4IAGBYbju7RMEmXd9l41QMJ98E8bS5o0Kjht1E477hhdy/qGiWHWIWAFhZfIVdP9+rIrEkVFXGFFaC9n7gX0UwnTN81LkizQUCaVqRnYJBgXE0brMCzQK4LNJsjftnM6y0wvo4EgqeRG//g0DHGwsUwoQH1YaVd9L52YT8A83rWKDGwzvZFhEeacUhIO36g==" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Dec 19 22:16:15 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:15Z" level=debug msg="connected to ap-gew4.spotify.com:443" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="completed keyexchange" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="completed challenge" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=info msg="authenticated AP" username="31************************ci" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=info msg="authenticated Login5" username="31************************ci" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="initializing zeroconf session" username="31************************ci" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="dealer connection opened" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=trace msg="starting accesspoint recv loop" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=trace msg="starting dealer recv loop" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=trace msg="received accesspoint ping" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="received connection id: OWM5ZDEzOWUtOTA0...MTY5MEQ2QkVBQQ==" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=trace msg="received accesspoint pong ack" Dec 19 22:16:16 volumio-adriana go-librespot[5243]: time="2025-12-19T22:16:16Z" level=debug msg="put connect state because NEW_DEVICE" Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:17 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:17 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:17 volumio-adriana sudo[5268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:17 volumio-adriana sudo[5268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:17 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:17 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:17 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:17 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:17 volumio-adriana go-librespot[5270]: go-librespot daemon starting... Dec 19 22:16:17 volumio-adriana sudo[5268]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:17 volumio-adriana go-librespot[5271]: time="2025-12-19T22:16:17Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:17 volumio-adriana go-librespot[5271]: time="2025-12-19T22:16:17Z" level=debug msg="app state loaded" Dec 19 22:16:17 volumio-adriana go-librespot[5271]: time="2025-12-19T22:16:17Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 19 22:16:17 volumio-adriana volumio[1120]: info: Creating Spotify config file Dec 19 22:16:17 volumio-adriana volumio[1120]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 22:16:17 volumio-adriana volumio[1120]: info: Spotify config file written Dec 19 22:16:17 volumio-adriana sudo[5278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 19 22:16:17 volumio-adriana sudo[5278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:17 volumio-adriana systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 19 22:16:17 volumio-adriana systemd[1]: go-librespot-daemon.service: Killing process 5279 (go-librespot) with signal SIGKILL. Dec 19 22:16:17 volumio-adriana volumio[1120]: info: Getting Spotify volume Dec 19 22:16:17 volumio-adriana systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 19 22:16:17 volumio-adriana systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:17 volumio-adriana volumio[1120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 22:16:17 volumio-adriana volumio[1120]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 19 22:16:17 volumio-adriana volumio[1120]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 19 22:16:17 volumio-adriana volumio[1120]: errno: -111, Dec 19 22:16:17 volumio-adriana volumio[1120]: code: 'ECONNREFUSED', Dec 19 22:16:17 volumio-adriana volumio[1120]: syscall: 'connect', Dec 19 22:16:17 volumio-adriana volumio[1120]: address: '127.0.0.1', Dec 19 22:16:17 volumio-adriana volumio[1120]: port: 9879, Dec 19 22:16:17 volumio-adriana volumio[1120]: response: undefined Dec 19 22:16:17 volumio-adriana volumio[1120]: } Dec 19 22:16:17 volumio-adriana volumio[1120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 22:16:17 volumio-adriana systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 19 22:16:17 volumio-adriana go-librespot[5281]: go-librespot daemon starting... Dec 19 22:16:17 volumio-adriana sudo[5278]: pam_unix(sudo:session): session closed for user root Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=info msg="running go-librespot 0.4.0" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="app state loaded" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" 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 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" 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 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" 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 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=info msg="zeroconf server listening on port 42435" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="obtained new client token: AABRp6ZIKI66/3LHKbtAmoDuMW2cZXAKRP/FOZRZFnFHhlNMiYdhzXo8B8jJwsYll59x+w+OaviVLcq+gQTnstVM4AHVdIOXu6xm/164lHYTDePLcKRLVeUKN9afLAw2F4a6ILRyy/WiZFB2FSfExCJCmNQGfKh8a0c/Iq/+TZDdM7SIO8xG1tgGMZpiv/X03C+16kZPI2FxZEX4zXvZeKCSkU2Wo1qlnvPvGDbrBaKCSrZdX2pBWA==" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="connected to ap-gew4.spotify.com:4070" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="completed keyexchange" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="completed challenge" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=info msg="authenticated AP" username="31************************ci" Dec 19 22:16:17 volumio-adriana sudo[5304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 22:15' Dec 19 22:16:17 volumio-adriana sudo[5304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=info msg="authenticated Login5" username="31************************ci" Dec 19 22:16:17 volumio-adriana go-librespot[5286]: time="2025-12-19T22:16:17Z" level=debug msg="initializing zeroconf session" username="31************************ci" 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"