Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jan 08 18:53:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::getState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jan 08 18:53:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::getState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces system playlist update Jan 08 18:53:24 volumio volumio[1205]: info: Ignoring MPD Status Update Jan 08 18:53:24 volumio volumio[1205]: info: Jan 08 18:53:24 volumio volumio[1205]: ---------------------------- MPD announces state update: player Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::getState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand status Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 9ms Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand status took 6 milliseconds Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 6ms Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand status took 9 milliseconds Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 8ms Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand status took 6 milliseconds Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseState Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::pushState Jan 08 18:53:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jan 08 18:53:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 22ms Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 08 18:53:24 volumio volumio[1205]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jan 08 18:53:24 volumio volumio[1205]: verbose: ControllerMpd::parseTrackInfo Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::pushState Jan 08 18:53:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jan 08 18:53:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 08 18:53:24 volumio volumio[1205]: info: ControllerMpd::pushState Jan 08 18:53:24 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jan 08 18:53:24 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:24 volumio volumio[1205]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 23ms Jan 08 18:53:24 volumio volumio[1205]: info: ------------------------------ 20ms Jan 08 18:53:39 volumio volumio[1205]: info: [1767909219510] [80s80s] Pushing the next song state Cock Robin - Just Around the Corner and getting next track. Jan 08 18:53:39 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:39 volumio volumio[1205]: info: CoreCommandRouter::servicePushState Jan 08 18:53:39 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:39 volumio volumio[1205]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music124/v4/56/f5/8a/56f58a84-134e-7dd9-b278-aaae456248d2/source/600x600bb.jpg","name":"Cock Robin - Just Around the Corner","title":"Just Around the Corner","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"235","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 08 18:53:39 volumio volumio[1205]: verbose: CURRENT POSITION 0 Jan 08 18:53:39 volumio volumio[1205]: info: CoreStateMachine::syncState stateService play Jan 08 18:53:39 volumio volumio[1205]: info: CoreStateMachine::syncState currentStatus pause Jan 08 18:53:39 volumio volumio[1205]: info: CoreStateMachine::pushState Jan 08 18:53:39 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:39 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 18:53:39 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 08 18:53:39 volumio volumio[1205]: info: [1767909219528] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=1 Jan 08 18:53:39 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:53:40 volumio volumio[1205]: info: [1767909220268] [80s80s] received new event containing 1 songs. Jan 08 18:53:40 volumio volumio[1205]: info: [1767909220269] [80s80s] PlayNextTrack API delay: 30 Jan 08 18:53:40 volumio volumio[1205]: info: [1767909220273] [80s80s] Setting timer to: 235000 milliseconds. Jan 08 18:53:44 volumio volumio[1205]: info: Received OAUTH Data Jan 08 18:53:44 volumio volumio[1205]: info: Executing Spotify Oauth Login Jan 08 18:53:44 volumio volumio[1205]: info: Saving Spotify Refresh Token Jan 08 18:53:44 volumio volumio[1205]: info: New Spotify access tokenBQAOLTXJf6... Jan 08 18:53:44 volumio volumio[1205]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 08 18:53:45 volumio sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 08 18:53:45 volumio sudo[4896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:45 volumio sudo[4896]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:45 volumio sudo[4898]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 08 18:53:45 volumio sudo[4898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:45 volumio sudo[4898]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:45 volumio volumio[1205]: SPOTIFY: User informations: {"country":"AR","display_name":"Sergio","email":"francadesgc@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/iab15vt8l3szssgd0z5qtdyue"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/iab15vt8l3szssgd0z5qtdyue","id":"iab15vt8l3szssgd0z5qtdyue","images":[],"product":"free","type":"user","uri":"spotify:user:iab15vt8l3szssgd0z5qtdyue"} Jan 08 18:53:45 volumio volumio[1205]: info: Creating Spotify config file Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 18:53:45 volumio volumio[1205]: info: Spotify config file written Jan 08 18:53:45 volumio sudo[4903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 08 18:53:45 volumio sudo[4903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:45 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 08 18:53:45 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 08 18:53:45 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:45 volumio volumio[1205]: verbose: New Socket.io Connection to 192.168.1.18 from 192.168.1.11 UA: Mozilla/5.0 (Linux; Android 14; SM-A236M Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:53:45 volumio volumio[1205]: info: Connection to go-librespot Websocket closed Jan 08 18:53:45 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:45 volumio go-librespot[4905]: go-librespot daemon starting... Jan 08 18:53:45 volumio sudo[4903]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=info msg="running go-librespot 0.6.2" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=debug msg="app state loaded" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=debug msg="stored credentials not found" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::volumioGetVisibleSources Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:45 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 08 18:53:45 volumio volumio[1205]: info: Received Get System Info Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 18:53:45 volumio volumio[1205]: info: Discovery: Getting this device information Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:45 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:45 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:45 volumio volumio[1205]: info: Listing playlists Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 18:53:45 volumio volumio[1205]: info: New Spotify access tokenBQDvb-I8UB... Jan 08 18:53:45 volumio volumio[1205]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 08 18:53:45 volumio go-librespot[4906]: time="2026-01-08T18:53:45-03:00" level=info msg="zeroconf server listening on port 36857" Jan 08 18:53:45 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 08 18:53:46 volumio volumio[1205]: SPOTIFY: User informations: {"country":"AR","display_name":"Sergio","email":"francadesgc@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/iab15vt8l3szssgd0z5qtdyue"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/iab15vt8l3szssgd0z5qtdyue","id":"iab15vt8l3szssgd0z5qtdyue","images":[],"product":"free","type":"user","uri":"spotify:user:iab15vt8l3szssgd0z5qtdyue"} Jan 08 18:53:46 volumio volumio[1205]: info: Spotify Successfully logged in Jan 08 18:53:46 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 08 18:53:46 volumio volumio[1205]: info: [1767909226011] CoreMusicLibrary::Adding element Spotify Jan 08 18:53:46 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 08 18:53:46 volumio volumio[1205]: Cannot find translation for source 80s80s Radio Jan 08 18:53:46 volumio volumio[1205]: Cannot find translation for source Spotify Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="obtained new client token: AABWv300CE2PfhtlLzxW2o2IcJJVXN2OlrSZA5x2yTwwj9nZdAdYoBWYi1ufwFJJV5Vc4WZtbCuLTvV3twcBHZdpZBpY36gJV03Wg1KBmrkGVQq0lEk2SbVFvnUEsuuIOtfJKhtVOXZEHO4FVE5FjCYeNg6pOufYO9scPjzd1zi57Ztvhg9ZUoyhJMQ/ThUOTHxD1tyCRJI5PzzeqyTvDiTIc8BsmDVZjlbwYb+S+t1TNK/PCyjup/k8" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="completed keyexchange" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="completed challenge" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=info msg="authenticated AP" username="ia*********************ue" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=info msg="authenticated Login5" username="ia*********************ue" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="stored credentials" username="ia*********************ue" Jan 08 18:53:46 volumio go-librespot[4906]: time="2026-01-08T18:53:46-03:00" level=debug msg="initializing zeroconf session" username="ia*********************ue" Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 18:53:47 volumio volumio[1205]: info: Received Get System Info Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 18:53:47 volumio volumio[1205]: info: Discovery: Getting this device information Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:47 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="dealer connection opened" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=trace msg="starting accesspoint recv loop" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=trace msg="starting dealer recv loop" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=trace msg="received accesspoint ping" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="received connection id: Zjg5MTEwZTQtOWI0...MkI2QTcxMzI5Mg==" Jan 08 18:53:47 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=trace msg="received accesspoint pong ack" Jan 08 18:53:47 volumio go-librespot[4906]: time="2026-01-08T18:53:47-03:00" level=debug msg="put connect state because NEW_DEVICE" Jan 08 18:53:48 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 18:53:48 volumio volumio[1205]: info: Received Get System Info Jan 08 18:53:48 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 18:53:48 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 18:53:48 volumio volumio[1205]: info: Discovery: Getting this device information Jan 08 18:53:48 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:48 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:48 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 18:53:48 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:53:48 volumio go-librespot[4906]: time="2026-01-08T18:53:48-03:00" level=debug msg="new websocket client" Jan 08 18:53:48 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:53:48 volumio volumio[1205]: info: go-librespot daemon successfully initialized Jan 08 18:53:51 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:53:51 volumio volumio[1205]: info: Spotify volume: 100 Jan 08 18:53:51 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 08 18:53:51 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:53:51 volumio go-librespot[4906]: time="2026-01-08T18:53:51-03:00" level=debug msg="new websocket client" Jan 08 18:53:51 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:51 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:51 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:53:51 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:53:51 volumio volumio[1205]: SPOTIFY: SPOTIFY VOLUME 100 Jan 08 18:53:51 volumio volumio[1205]: SPOTIFY: VOLUMIO VOLUME 86 Jan 08 18:53:51 volumio volumio[1205]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 08 18:53:51 volumio volumio[1205]: info: Setting Spotify Volume from Volumio: 86 Jan 08 18:53:52 volumio volumio[1205]: info: CoreCommandRouter::volumioPause Jan 08 18:53:52 volumio volumio[1205]: info: CoreStateMachine::pause Jan 08 18:53:52 volumio volumio[1205]: info: CoreStateMachine::stPlaybackTimer Jan 08 18:53:52 volumio volumio[1205]: info: CoreStateMachine::servicePause Jan 08 18:53:52 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:52 volumio volumio[1205]: info: CoreCommandRouter::servicePause Jan 08 18:53:52 volumio volumio[1205]: error: WARNING: No pause method for service 80s80s Jan 08 18:53:52 volumio volumio[1205]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jan 08 18:53:52 volumio volumio[1205]: info: Sending Spotify command with payload to local API: /player/volume Jan 08 18:53:52 volumio go-librespot[4906]: time="2026-01-08T18:53:52-03:00" level=debug msg="update volume requested to 56360/65535" Jan 08 18:53:53 volumio go-librespot[4906]: time="2026-01-08T18:53:53-03:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 08 18:53:53 volumio go-librespot[4906]: time="2026-01-08T18:53:53-03:00" level=trace msg="emitting websocket event: volume" Jan 08 18:53:53 volumio volumio[1205]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 08 18:53:53 volumio volumio[1205]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 08 18:53:53 volumio volumio[1205]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 08 18:53:53 volumio volumio[1205]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 08 18:53:54 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:53:54 volumio volumio[1205]: info: Spotify volume: 86 Jan 08 18:53:54 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:53:54 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:53:54 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:53:54 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:53:55 volumio volumio[1205]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 08 18:53:55 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 08 18:53:55 volumio volumio[1205]: info: Creating Spotify config file Jan 08 18:53:55 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 18:53:55 volumio volumio[1205]: info: Spotify config file written Jan 08 18:53:55 volumio sudo[4952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 08 18:53:55 volumio sudo[4952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:55 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 08 18:53:55 volumio systemd[1]: go-librespot-daemon.service: Killing process 4907 (go-librespot) with signal SIGKILL. Jan 08 18:53:55 volumio volumio[1205]: info: Connection to go-librespot Websocket closed Jan 08 18:53:55 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 08 18:53:55 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:55 volumio volumio[1205]: info: Connection to go-librespot Websocket closed Jan 08 18:53:55 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 08 18:53:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:55 volumio go-librespot[4957]: go-librespot daemon starting... Jan 08 18:53:55 volumio sudo[4952]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=info msg="running go-librespot 0.6.2" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=debug msg="app state loaded" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=info msg="zeroconf server listening on port 36215" Jan 08 18:53:55 volumio go-librespot[4958]: time="2026-01-08T18:53:55-03:00" level=debug msg="obtained new client token: AACMD1L6ZzOUIZp55xzaxYsRz3lHpFVoA3irnrP25V0Eu0TYtf0Enqijbgaqmt6+D6boTkBcy9+zipA3K+KkF2Bja2CksKqiE5JGU9ZMboySOyumwOzR2dJVWzPlt/YVLzH1S8X/8q8GXtJBttTieNpSvgxhh4dGUwtg1ZLBSI0xWyBm1bKgcQoA8nLSgwUEBbmnjNZqrykLKUm+Wu2W4z/mZ1SXlr2E1owEfe90HpO1ifQXSyAAKVBn" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=debug msg="connected to ap-guc3.spotify.com:443" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=debug msg="completed keyexchange" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=debug msg="completed challenge" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=info msg="authenticated AP" username="ia*********************ue" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=info msg="authenticated Login5" username="ia*********************ue" Jan 08 18:53:56 volumio go-librespot[4958]: time="2026-01-08T18:53:56-03:00" level=debug msg="initializing zeroconf session" username="ia*********************ue" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="dealer connection opened" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=trace msg="starting accesspoint recv loop" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=trace msg="starting dealer recv loop" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=trace msg="received accesspoint ping" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="received connection id: M2YyOWVkZDQtYzgz...MEIxN0UxNjI1OA==" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=trace msg="received accesspoint pong ack" Jan 08 18:53:57 volumio go-librespot[4958]: time="2026-01-08T18:53:57-03:00" level=debug msg="put connect state because NEW_DEVICE" Jan 08 18:53:57 volumio volumio[1205]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 08 18:53:57 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 08 18:53:57 volumio volumio[1205]: info: Creating Spotify config file Jan 08 18:53:57 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 18:53:57 volumio volumio[1205]: info: Spotify config file written Jan 08 18:53:57 volumio sudo[4972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 08 18:53:57 volumio sudo[4972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:57 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 08 18:53:57 volumio systemd[1]: go-librespot-daemon.service: Killing process 4962 (go-librespot) with signal SIGKILL. Jan 08 18:53:57 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 08 18:53:57 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:57 volumio go-librespot[4975]: go-librespot daemon starting... Jan 08 18:53:57 volumio sudo[4972]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=info msg="running go-librespot 0.6.2" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="app state loaded" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 08 18:53:58 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="new websocket client" Jan 08 18:53:58 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:53:58 volumio volumio[1205]: info: go-librespot daemon successfully initialized Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=info msg="zeroconf server listening on port 44339" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="obtained new client token: AAD88xpyHPmJb6FtAcRXYo5IWPLfRP+GXeTNrSWBxqacKotCLqG+VNqVC4KWlJbtbeaCoeveLK5ce/F4zky4UoM9vKl2bap4vYtkG1BMB4KJX68XmX0z/yzZhUS7fWLel+z5DHPPMB7NGw4CpnQC266mWn7fMOQbccMJkZkdeUaOM2VChaj2Silp/P4azhGZ5TiI4056LuiaztN3/dVHvwuaCaGs9xhtnJ3IsOiSzoQzh57e8Pyz9EYG" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 08 18:53:58 volumio volumio[1205]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 08 18:53:58 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 08 18:53:58 volumio volumio[1205]: info: Creating Spotify config file Jan 08 18:53:58 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 18:53:58 volumio volumio[1205]: info: Spotify config file written Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="completed keyexchange" Jan 08 18:53:58 volumio go-librespot[4976]: time="2026-01-08T18:53:58-03:00" level=debug msg="completed challenge" Jan 08 18:53:58 volumio sudo[4988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 08 18:53:58 volumio sudo[4988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:53:59 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 08 18:53:59 volumio systemd[1]: go-librespot-daemon.service: Killing process 4978 (go-librespot) with signal SIGKILL. Jan 08 18:53:59 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 08 18:53:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:59 volumio volumio[1205]: info: Connection to go-librespot Websocket closed Jan 08 18:53:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:53:59 volumio go-librespot[4990]: go-librespot daemon starting... Jan 08 18:53:59 volumio sudo[4988]: pam_unix(sudo:session): session closed for user root Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=info msg="running go-librespot 0.6.2" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="app state loaded" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=info msg="zeroconf server listening on port 37333" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="obtained new client token: AADxJUWIdGSBmTDYyd9Qq3f7l+HQ0GFYZ5UPr1994IULrL4K0N9b8Itxv5U8glvbjKJdIzmSnGRxv30AEVpmUTehzpdPK+nlz66UpYzWCDeYD/Y2CsYS4pKEO5YLtVWmIZMpF+lRdmdNqpSFqm2zWTmMENyKLDmPgfom5Us4LwH1icWgJRWGNskbucfLKKc00z65HPVjVR6kOMihj5Dhf36fYd78/nK1iWqIQk6jSQrRRUtawe9gd19m" Jan 08 18:53:59 volumio go-librespot[4991]: time="2026-01-08T18:53:59-03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="completed keyexchange" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="completed challenge" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=info msg="authenticated AP" username="ia*********************ue" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=info msg="authenticated Login5" username="ia*********************ue" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="initializing zeroconf session" username="ia*********************ue" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="dealer connection opened" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=trace msg="starting accesspoint recv loop" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=trace msg="starting dealer recv loop" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=trace msg="received accesspoint ping" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 08 18:54:00 volumio go-librespot[4991]: time="2026-01-08T18:54:00-03:00" level=debug msg="received connection id: YWM4YzQ3OTktMWE0...M0U2NTExRjdFOQ==" Jan 08 18:54:00 volumio volumio[1205]: info: go-librespot daemon successfully initialized Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=debug msg="put connect state because NEW_DEVICE" Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=trace msg="received accesspoint pong ack" Jan 08 18:54:01 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:54:01 volumio volumio[1205]: info: Spotify volume: 100 Jan 08 18:54:01 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:54:01 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:01 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:01 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:54:01 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:54:01 volumio volumio[1205]: SPOTIFY: SPOTIFY VOLUME 100 Jan 08 18:54:01 volumio volumio[1205]: SPOTIFY: VOLUMIO VOLUME 86 Jan 08 18:54:01 volumio volumio[1205]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 08 18:54:01 volumio volumio[1205]: info: Setting Spotify Volume from Volumio: 86 Jan 08 18:54:01 volumio go-librespot[4991]: time="2026-01-08T18:54:01-03:00" level=debug msg="new websocket client" Jan 08 18:54:01 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:54:02 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:54:02 volumio go-librespot[4991]: time="2026-01-08T18:54:02-03:00" level=debug msg="new websocket client" Jan 08 18:54:02 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:54:02 volumio volumio[1205]: info: go-librespot daemon successfully initialized Jan 08 18:54:02 volumio volumio[1205]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Jan 08 18:54:02 volumio volumio[1205]: info: Sending Spotify command with payload to local API: /player/volume Jan 08 18:54:02 volumio go-librespot[4991]: time="2026-01-08T18:54:02-03:00" level=debug msg="update volume requested to 56360/65535" Jan 08 18:54:03 volumio go-librespot[4991]: time="2026-01-08T18:54:03-03:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 08 18:54:03 volumio go-librespot[4991]: time="2026-01-08T18:54:03-03:00" level=trace msg="emitting websocket event: volume" Jan 08 18:54:03 volumio volumio[1205]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 08 18:54:03 volumio volumio[1205]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 08 18:54:03 volumio volumio[1205]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Jan 08 18:54:03 volumio volumio[1205]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 18:54:03 volumio volumio[1205]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 08 18:54:03 volumio volumio[1205]: info: Received Get System Version Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 18:54:03 volumio volumio[1205]: info: Received Get System Info Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 18:54:03 volumio volumio[1205]: info: Discovery: Getting this device information Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:03 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 18:54:03 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:54:04 volumio go-librespot[4991]: time="2026-01-08T18:54:04-03:00" level=debug msg="new websocket client" Jan 08 18:54:04 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:54:04 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:54:04 volumio volumio[1205]: info: Spotify volume: 86 Jan 08 18:54:04 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:54:04 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:04 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:04 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:54:05 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:54:05 volumio volumio[1205]: info: Spotify volume: 86 Jan 08 18:54:05 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:54:05 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Jan 08 18:54:05 volumio go-librespot[4991]: time="2026-01-08T18:54:05-03:00" level=debug msg="new websocket client" Jan 08 18:54:05 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:05 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:05 volumio volumio[1205]: info: Connection to go-librespot Websocket established Jan 08 18:54:05 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:54:05 volumio volumio[1205]: info: CoreCommandRouter::volumioPause Jan 08 18:54:05 volumio volumio[1205]: info: CoreStateMachine::pause Jan 08 18:54:07 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:54:07 volumio volumio[1205]: info: Spotify volume: 86 Jan 08 18:54:07 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:54:07 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:07 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:07 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:54:08 volumio volumio[1205]: info: Getting Spotify volume Jan 08 18:54:08 volumio volumio[1205]: info: Spotify volume: 86 Jan 08 18:54:08 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 08 18:54:08 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Jan 08 18:54:08 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:08 volumio volumio[1205]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Jan 08 18:54:12 volumio volumio[1205]: info: CoreCommandRouter::volumioGetQueue Jan 08 18:54:12 volumio volumio[1205]: info: CoreStateMachine::getQueue Jan 08 18:54:12 volumio volumio[1205]: info: CorePlayQueue::getQueue Jan 08 18:54:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 08 18:54:18 volumio volumio[1205]: info: In handleBrowseUri, curUri=spotify Jan 08 18:54:18 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:18 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:18 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:18 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:20 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 08 18:54:20 volumio volumio[1205]: info: In handleBrowseUri, curUri=spotify/playlists Jan 08 18:54:20 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:30 volumio go-librespot[4991]: time="2026-01-08T18:54:30-03:00" level=trace msg="sent dealer ping" Jan 08 18:54:31 volumio go-librespot[4991]: time="2026-01-08T18:54:31-03:00" level=trace msg="received dealer pong" Jan 08 18:54:39 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 08 18:54:39 volumio volumio[1205]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:10ngQaq7dHJ1ahsv6Kk2nT Jan 08 18:54:40 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 08 18:54:40 volumio volumio[1205]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:10ngQaq7dHJ1ahsv6Kk2nT Jan 08 18:54:41 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2gGdO0zLa9W8ce1Ig0BzFK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2tCJ6BVzelFEgP9ZYqlS4y Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7jwQlYGpOml9ETGre1HqGA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3AEHdIKNp1ce0Q3afGnKKf Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1bOEl3oW1TzEu69tZQ2PKp Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:4lujyhscsnUjqDSxvixq5h Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7jwQlYGpOml9ETGre1HqGA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:30lTCdPfI318o6hGKhyLl3 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2QN9zL3SzXdGqqmXO68gFp Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3NsIZGKtO06yfTJH3zXEni Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1bOEl3oW1TzEu69tZQ2PKp Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0Ylyq4VhTXoRiXRpn2Butm Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1fP4i0PcURseJwS392c2fU Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7IqGDiiRTGI3yUPE5erXjo Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:4dKgJOFyPs5qMTC925ikc3 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1Rfb5qEJFwECftSHg3gzgk Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7C31EEygajGR3RcMksZhQD Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3MynFzEMkDMZgH7SglRlNK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:59Io3GoipxsbQsrvpkzNvX Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0VpMLVmYw3C9XWltMs4fXK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:6QAAQAxgK86u1FP2wqvSQh Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:20ltqEKhE7LyNNZ13Br9zE Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3QDKc5IOsM0SB8ZpOp5WDG Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2155Oaz5YBYwpYNDldh7bi Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1JSFWdP58vS4xemat4mOWG Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2uJzzd1nnuTGgTF7nSBv1G Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3lI8bYbuE9utsu5lQ0CeSO Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:00S2ugx6blY0o2RcXyJqw0 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5PvQ94rMWihogUjW95JXjq Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0UtSCr8KqKDGhwHmsnrIwH Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3OfNXAA1M3hmyjCAScsKwQ Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7BLdwcYz4cCgm5KsySAznb Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:546t5OfGPJmVbg7TJFGMxR Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7Ja05xEhiznSZeKFIjbpqj Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3vkYspVLTTaeJhAsBXh491 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0EeeGMPV5wB4b0YXGMWuH0 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0VE6QOTd7RZymFuIOawi6j Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:04t0G1yf4nVc5qxTb8ASQu Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:507BSWVlsB0UVG6IsFtr9j Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3n3dUYDOewlb7RnWk3sb4k Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1bNKBsSLpZ8vfASu45ctgA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3goctAzym2AXmgTyER3QQ1 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5BxTRn2vnkTr3kAq4nHGjt Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5hPaDzRIdbZD7Fy3tledhM Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0Bq7UQv3bdHZX7NhNKlJCJ Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7MDMm5G0mED1rz8585fSvS Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:30Ibd8DfBVvHriUOdJPpHA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:78n0OeThTl9hDHcq3EVjrh Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:4tkBcDeidexyxGik6H1lhK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5qQRCOGEDEggAE54ZJCFfq Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:2gGdO0zLa9W8ce1Ig0BzFK in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:2gGdO0zLa9W8ce1Ig0BzFK Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:2tCJ6BVzelFEgP9ZYqlS4y in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:2tCJ6BVzelFEgP9ZYqlS4y Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:7jwQlYGpOml9ETGre1HqGA in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7jwQlYGpOml9ETGre1HqGA Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:3AEHdIKNp1ce0Q3afGnKKf in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3AEHdIKNp1ce0Q3afGnKKf Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:1bOEl3oW1TzEu69tZQ2PKp in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:1bOEl3oW1TzEu69tZQ2PKp Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:4lujyhscsnUjqDSxvixq5h in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:4lujyhscsnUjqDSxvixq5h Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2gGdO0zLa9W8ce1Ig0BzFK","service":"spop","name":"Somebody's Watching Me","artist":"Rockwell","album":"12\" 80s Classics","type":"song","duration":293,"albumart":"https://i.scdn.co/image/ab67616d0000b2735284333459b08de72e2bda0d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:7jwQlYGpOml9ETGre1HqGA in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7jwQlYGpOml9ETGre1HqGA Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tCJ6BVzelFEgP9ZYqlS4y","service":"spop","name":"Calientita","artist":"Reywil Alexander","album":"Calientita","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27305b1ddef8066b12228f708ff","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: CoreCommandRouter::volumioPause Jan 08 18:54:41 volumio volumio[1205]: info: CoreStateMachine::pause Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7jwQlYGpOml9ETGre1HqGA","service":"spop","name":"Somebody's Watching Me","artist":"Rockwell","album":"Somebody's Watching Me","type":"song","duration":298,"albumart":"https://i.scdn.co/image/ab67616d0000b27357de8cb9bbbe22a9e71c8af7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:30lTCdPfI318o6hGKhyLl3 in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:30lTCdPfI318o6hGKhyLl3 Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:2QN9zL3SzXdGqqmXO68gFp in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:2QN9zL3SzXdGqqmXO68gFp Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AEHdIKNp1ce0Q3afGnKKf","service":"spop","name":"Boomerang","artist":"Norci Morales","album":"Boomerang","type":"song","duration":120,"albumart":"https://i.scdn.co/image/ab67616d0000b2737016793443884777a828eedd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:3NsIZGKtO06yfTJH3zXEni in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3NsIZGKtO06yfTJH3zXEni Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bOEl3oW1TzEu69tZQ2PKp","service":"spop","name":"Knife","artist":"Rockwell","album":"Somebody's Watching Me","type":"song","duration":306,"albumart":"https://i.scdn.co/image/ab67616d0000b27357de8cb9bbbe22a9e71c8af7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Using cached record of: spotify:track:1bOEl3oW1TzEu69tZQ2PKp Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4lujyhscsnUjqDSxvixq5h","service":"spop","name":"Vamos A Volar","artist":"Jey Jey","album":"Vamos A Volar","type":"song","duration":117,"albumart":"https://i.scdn.co/image/ab67616d0000b2736ab042dc84a4dd0e2b090b56","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:0Ylyq4VhTXoRiXRpn2Butm in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0Ylyq4VhTXoRiXRpn2Butm Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7jwQlYGpOml9ETGre1HqGA","service":"spop","name":"Somebody's Watching Me","artist":"Rockwell","album":"Somebody's Watching Me","type":"song","duration":298,"albumart":"https://i.scdn.co/image/ab67616d0000b27357de8cb9bbbe22a9e71c8af7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:1fP4i0PcURseJwS392c2fU in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:1fP4i0PcURseJwS392c2fU Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:7IqGDiiRTGI3yUPE5erXjo in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7IqGDiiRTGI3yUPE5erXjo Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QN9zL3SzXdGqqmXO68gFp","service":"spop","name":"Somebody's Watching Me - Single Version","artist":"Rockwell","album":"Somebody’s Watching Me (Sped Up)","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b6773d6e0df6eedefb20190","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30lTCdPfI318o6hGKhyLl3","service":"spop","name":"Butifarra","artist":"Harry smith","album":"Butifarra","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b27373aabaf70353a5d58f3fa95b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:4dKgJOFyPs5qMTC925ikc3 in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:4dKgJOFyPs5qMTC925ikc3 Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3NsIZGKtO06yfTJH3zXEni","service":"spop","name":"Kitty Pink","artist":"Harry smith","album":"Kitty Pink","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273ac402a2de5e942e04c5b31f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:41 volumio volumio[1205]: info: Exploding uri spotify:track:1Rfb5qEJFwECftSHg3gzgk in service spop Jan 08 18:54:41 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:1Rfb5qEJFwECftSHg3gzgk Jan 08 18:54:41 volumio volumio[1205]: info: Preload queue cleared Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7C31EEygajGR3RcMksZhQD Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3MynFzEMkDMZgH7SglRlNK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:59Io3GoipxsbQsrvpkzNvX Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0VpMLVmYw3C9XWltMs4fXK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:6QAAQAxgK86u1FP2wqvSQh Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:20ltqEKhE7LyNNZ13Br9zE Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3QDKc5IOsM0SB8ZpOp5WDG Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2155Oaz5YBYwpYNDldh7bi Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1JSFWdP58vS4xemat4mOWG Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:2uJzzd1nnuTGgTF7nSBv1G Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3lI8bYbuE9utsu5lQ0CeSO Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:00S2ugx6blY0o2RcXyJqw0 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5PvQ94rMWihogUjW95JXjq Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0UtSCr8KqKDGhwHmsnrIwH Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3OfNXAA1M3hmyjCAScsKwQ Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7BLdwcYz4cCgm5KsySAznb Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:546t5OfGPJmVbg7TJFGMxR Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7Ja05xEhiznSZeKFIjbpqj Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3vkYspVLTTaeJhAsBXh491 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0EeeGMPV5wB4b0YXGMWuH0 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0VE6QOTd7RZymFuIOawi6j Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:04t0G1yf4nVc5qxTb8ASQu Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:507BSWVlsB0UVG6IsFtr9j Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3n3dUYDOewlb7RnWk3sb4k Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:1bNKBsSLpZ8vfASu45ctgA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:3goctAzym2AXmgTyER3QQ1 Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5BxTRn2vnkTr3kAq4nHGjt Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5hPaDzRIdbZD7Fy3tledhM Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:0Bq7UQv3bdHZX7NhNKlJCJ Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:7MDMm5G0mED1rz8585fSvS Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:30Ibd8DfBVvHriUOdJPpHA Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:78n0OeThTl9hDHcq3EVjrh Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:4tkBcDeidexyxGik6H1lhK Jan 08 18:54:41 volumio volumio[1205]: info: Preloading song: spotify:track:5qQRCOGEDEggAE54ZJCFfq Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:7C31EEygajGR3RcMksZhQD in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7C31EEygajGR3RcMksZhQD Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Ylyq4VhTXoRiXRpn2Butm","service":"spop","name":"Dança Da Favela","artist":"Stidlmusic","album":"Dança Da Favela","type":"song","duration":92,"albumart":"https://i.scdn.co/image/ab67616d0000b273daedc5310db201b4ee6835a3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1fP4i0PcURseJwS392c2fU","service":"spop","name":"Somebody Is Watching Me","artist":"Rockwell","album":"Somebody Is Watchin' Me","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273944b04c4a34ba595dcd9ad14","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:3MynFzEMkDMZgH7SglRlNK in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3MynFzEMkDMZgH7SglRlNK Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7IqGDiiRTGI3yUPE5erXjo","service":"spop","name":"Dança Da Favela Speed","artist":"Stidlmusic","album":"Dança Da Favela Speed","type":"song","duration":81,"albumart":"https://i.scdn.co/image/ab67616d0000b273d71249ad46352875e6618676","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:59Io3GoipxsbQsrvpkzNvX in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:59Io3GoipxsbQsrvpkzNvX Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:0VpMLVmYw3C9XWltMs4fXK in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0VpMLVmYw3C9XWltMs4fXK Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4dKgJOFyPs5qMTC925ikc3","service":"spop","name":"Somebody's Watching Me - Syzz Remix","artist":"Rockwell","album":"Somebody's Watching Me (Syzz Remix)","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b2733d573071e9cb58a85185fd2a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Rfb5qEJFwECftSHg3gzgk","service":"spop","name":"Somebody's Watching Me - Urban Renewal Project Remix","artist":"Rockwell","album":"Somebody’s Watching Me (Urban Renewal Project Remix)","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273ce6e03984c02c9575a9869e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:6QAAQAxgK86u1FP2wqvSQh in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:6QAAQAxgK86u1FP2wqvSQh Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:20ltqEKhE7LyNNZ13Br9zE in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:20ltqEKhE7LyNNZ13Br9zE Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7C31EEygajGR3RcMksZhQD","service":"spop","name":"Dança Da Favela Slowed","artist":"Stidlmusic","album":"Dança Da Favela Slowed","type":"song","duration":115,"albumart":"https://i.scdn.co/image/ab67616d0000b273996a439df29f01d71473a8a5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:3QDKc5IOsM0SB8ZpOp5WDG in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3QDKc5IOsM0SB8ZpOp5WDG Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3MynFzEMkDMZgH7SglRlNK","service":"spop","name":"Somebody's Watching Me - John Morales M+M Extended Mix","artist":"Rockwell","album":"Somebody’s Watching Me (Deluxe Edition)","type":"song","duration":426,"albumart":"https://i.scdn.co/image/ab67616d0000b27398a2f8673ac879074d30d1ff","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:2155Oaz5YBYwpYNDldh7bi in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:2155Oaz5YBYwpYNDldh7bi Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:1JSFWdP58vS4xemat4mOWG in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:1JSFWdP58vS4xemat4mOWG Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:59Io3GoipxsbQsrvpkzNvX","service":"spop","name":"Rose","artist":"Velaskez","album":"Rose","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273c1a101c6be5ab7a2d8b485b3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:2uJzzd1nnuTGgTF7nSBv1G in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:2uJzzd1nnuTGgTF7nSBv1G Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VpMLVmYw3C9XWltMs4fXK","service":"spop","name":"Somebody's Watching Me - Sped Up","artist":"Rockwell","album":"Somebody’s Watching Me (Sped Up)","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b2733b6773d6e0df6eedefb20190","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6QAAQAxgK86u1FP2wqvSQh","service":"spop","name":"Yo Ando Con Dios","artist":"Stidlmusic","album":"Yo Ando Con Dios","type":"song","duration":105,"albumart":"https://i.scdn.co/image/ab67616d0000b273a0f43857945c43c3a842238a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:3lI8bYbuE9utsu5lQ0CeSO in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3lI8bYbuE9utsu5lQ0CeSO Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:20ltqEKhE7LyNNZ13Br9zE","service":"spop","name":"Mueve Esas Nalgas","artist":"Harry smith","album":"Mueve Esas Nalgas","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b2734e4ea75bf0f88aeea3803a8d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:00S2ugx6blY0o2RcXyJqw0 in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:00S2ugx6blY0o2RcXyJqw0 Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2155Oaz5YBYwpYNDldh7bi","service":"spop","name":"Midnight Shadows Slowed","artist":"Stidlmusic","album":"Midnight Shadows Slowed","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b273796557e4d1bc4b8f65a1105c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:5PvQ94rMWihogUjW95JXjq in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:5PvQ94rMWihogUjW95JXjq Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3QDKc5IOsM0SB8ZpOp5WDG","service":"spop","name":"Noche De Perreo","artist":"IZGO","album":"Noche De Perreo","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b273f30c24247540457b41e9b1cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1JSFWdP58vS4xemat4mOWG","service":"spop","name":"Tuku Taka","artist":"Harry smith","album":"Tuku Taka","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27307f1d29200a754867d02cfd3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:0UtSCr8KqKDGhwHmsnrIwH in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0UtSCr8KqKDGhwHmsnrIwH Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:3OfNXAA1M3hmyjCAScsKwQ in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3OfNXAA1M3hmyjCAScsKwQ Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3lI8bYbuE9utsu5lQ0CeSO","service":"spop","name":"La Vuelta Se Va A Dar","artist":"Cetee","album":"La Vuelta Se Va A Dar","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b2733885ebc85304afffa0343394","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:7BLdwcYz4cCgm5KsySAznb in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7BLdwcYz4cCgm5KsySAznb Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2uJzzd1nnuTGgTF7nSBv1G","service":"spop","name":"Midnight Shadows","artist":"Stidlmusic","album":"Midnight Shadows","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b2730f70269c3c91b69402ec924d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:00S2ugx6blY0o2RcXyJqw0","service":"spop","name":"Eclipse","artist":"Cetee","album":"Eclipse","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273f46291b82b4f96576e98160c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:546t5OfGPJmVbg7TJFGMxR in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:546t5OfGPJmVbg7TJFGMxR Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5PvQ94rMWihogUjW95JXjq","service":"spop","name":"Mi Loba","artist":"Reywil Alexander","album":"El De Tus Fantasias","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b27302d09a11b7d0381a1bb8fb0c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:7Ja05xEhiznSZeKFIjbpqj in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7Ja05xEhiznSZeKFIjbpqj Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0UtSCr8KqKDGhwHmsnrIwH","service":"spop","name":"De Revista","artist":"Reywil Alexander","album":"El De Tus Fantasias","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b27302d09a11b7d0381a1bb8fb0c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:3vkYspVLTTaeJhAsBXh491 in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3vkYspVLTTaeJhAsBXh491 Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3OfNXAA1M3hmyjCAScsKwQ","service":"spop","name":"Mi Melodía Favorita","artist":"Reywil Alexander","album":"El De Tus Fantasias","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b27302d09a11b7d0381a1bb8fb0c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:42 volumio volumio[1205]: info: Exploding uri spotify:track:0EeeGMPV5wB4b0YXGMWuH0 in service spop Jan 08 18:54:42 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0EeeGMPV5wB4b0YXGMWuH0 Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7BLdwcYz4cCgm5KsySAznb","service":"spop","name":"El Strike","artist":"Norci Morales","album":"¡Ya Tú Sabe' cómo Va!","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b2730d598eae32721822e1c6b7c2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:0VE6QOTd7RZymFuIOawi6j in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0VE6QOTd7RZymFuIOawi6j Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:546t5OfGPJmVbg7TJFGMxR","service":"spop","name":"El Ganador","artist":"Norci Morales","album":"¡Ya Tú Sabe' Cómo Va!","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b273de12fa65b1a006c37de5cd59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:04t0G1yf4nVc5qxTb8ASQu in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:04t0G1yf4nVc5qxTb8ASQu Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Ja05xEhiznSZeKFIjbpqj","service":"spop","name":"Baby Don't Hurt Me","artist":"CosmicJelly","album":"Baby Don't Hurt Me","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b2739f3b8f868aa1b15b014450a5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:507BSWVlsB0UVG6IsFtr9j in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:507BSWVlsB0UVG6IsFtr9j Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:3n3dUYDOewlb7RnWk3sb4k in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3n3dUYDOewlb7RnWk3sb4k Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0EeeGMPV5wB4b0YXGMWuH0","service":"spop","name":"Enero En Nueva York","artist":"Reywil Alexander","album":"Enero En Nueva York","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273e9eb85acff9995b2be0cf39c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3vkYspVLTTaeJhAsBXh491","service":"spop","name":"Twerking Black","artist":"Stidlmusic","album":"Twerking Black","type":"song","duration":135,"albumart":"https://i.scdn.co/image/ab67616d0000b2735fca85f3d224d7e9cd553c8c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:1bNKBsSLpZ8vfASu45ctgA in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:1bNKBsSLpZ8vfASu45ctgA Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VE6QOTd7RZymFuIOawi6j","service":"spop","name":"Tú Orgullo","artist":"Stidlmusic","album":"Mi Primer Millón","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273cda7d4a5f65f3be6764ed551","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:3goctAzym2AXmgTyER3QQ1 in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:3goctAzym2AXmgTyER3QQ1 Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04t0G1yf4nVc5qxTb8ASQu","service":"spop","name":"Mi Peor Error","artist":"Norci Morales","album":"Mi Peor Error","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b2734baed8c43470998cc9bf95ed","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:5BxTRn2vnkTr3kAq4nHGjt in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:5BxTRn2vnkTr3kAq4nHGjt Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:507BSWVlsB0UVG6IsFtr9j","service":"spop","name":"Modo Sad","artist":"Norci Morales","album":"¡Ya Tú Sabe' Cómo Va!","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273de12fa65b1a006c37de5cd59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:5hPaDzRIdbZD7Fy3tledhM in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:5hPaDzRIdbZD7Fy3tledhM Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3n3dUYDOewlb7RnWk3sb4k","service":"spop","name":"Nota Loca","artist":"Norci Morales","album":"¡Ya Tú Sabe' Cómo Va!","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273de12fa65b1a006c37de5cd59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:0Bq7UQv3bdHZX7NhNKlJCJ in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:0Bq7UQv3bdHZX7NhNKlJCJ Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bNKBsSLpZ8vfASu45ctgA","service":"spop","name":"Summer Days","artist":"CosmicJelly","album":"Summer Days","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b273f0cec65b3bd52ed4acdfbbcb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:7MDMm5G0mED1rz8585fSvS in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:7MDMm5G0mED1rz8585fSvS Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3goctAzym2AXmgTyER3QQ1","service":"spop","name":"One More Time","artist":"CosmicJelly","album":"One More Time","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b273ddc9a9b5f02d53f49a61e75b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:30Ibd8DfBVvHriUOdJPpHA in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:30Ibd8DfBVvHriUOdJPpHA Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BxTRn2vnkTr3kAq4nHGjt","service":"spop","name":"Otra Noche","artist":"Norci Morales","album":"¡Ya Tú Sabe' Cómo Va!","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273de12fa65b1a006c37de5cd59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:78n0OeThTl9hDHcq3EVjrh in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:78n0OeThTl9hDHcq3EVjrh Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5hPaDzRIdbZD7Fy3tledhM","service":"spop","name":"In The Name Of Luck","artist":"CosmicJelly","album":"In The Name Of Luck","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2736424750f8981d6580799c553","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:4tkBcDeidexyxGik6H1lhK in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:4tkBcDeidexyxGik6H1lhK Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Bq7UQv3bdHZX7NhNKlJCJ","service":"spop","name":"Otro Día","artist":"Reywil Alexander","album":"Otro Día","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273b689b945b4547bc47caa1fb7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: info: Exploding uri spotify:track:5qQRCOGEDEggAE54ZJCFfq in service spop Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: EXPLODING URI:spotify:track:5qQRCOGEDEggAE54ZJCFfq Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7MDMm5G0mED1rz8585fSvS","service":"spop","name":"Twerking","artist":"Stidlmusic","album":"Mi Primer Millón","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b273cda7d4a5f65f3be6764ed551","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30Ibd8DfBVvHriUOdJPpHA","service":"spop","name":"Otra Noche - Remix","artist":"Norci Morales","album":"¡Ya Tú Sabe' Cómo Va!","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273de12fa65b1a006c37de5cd59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:78n0OeThTl9hDHcq3EVjrh","service":"spop","name":"Around The World","artist":"CosmicJelly","album":"Around The World","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b2738b696a9b6404f980c80418fe","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4tkBcDeidexyxGik6H1lhK","service":"spop","name":"One In A Million","artist":"CosmicJelly","album":"One In A Million","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b2737cc441041c26d00e5383bd2e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:43 volumio volumio[1205]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5qQRCOGEDEggAE54ZJCFfq","service":"spop","name":"Me Supere","artist":"Reywil Alexander","album":"El De Tus Fantasias","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b27302d09a11b7d0381a1bb8fb0c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Jan 08 18:54:44 volumio volumio[1205]: info: CoreCommandRouter::volumioPause Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::pause Jan 08 18:54:44 volumio volumio[1205]: info: CoreCommandRouter::volumioNext Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::next Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::stop Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::updateTrackBlock Jan 08 18:54:44 volumio volumio[1205]: info: CorePlayQueue::getTrackBlock Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::stPlaybackTimer Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::pushState Jan 08 18:54:44 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:44 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 18:54:44 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Jan 08 18:54:44 volumio volumio[1205]: info: CoreStateMachine::serviceStop Jan 08 18:54:44 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Jan 08 18:54:44 volumio volumio[1205]: info: CoreCommandRouter::serviceStop Jan 08 18:54:44 volumio volumio[1205]: error: WARNING: No stop method for service 80s80s Jan 08 18:54:44 volumio volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 18:54:44 volumio volumio[1205]: TypeError: Cannot read properties of undefined (reading 'then') Jan 08 18:54:44 volumio volumio[1205]: at CoreStateMachine.next (/volumio/app/statemachine.js:1181:9) Jan 08 18:54:44 volumio volumio[1205]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Jan 08 18:54:44 volumio volumio[1205]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Jan 08 18:54:44 volumio volumio[1205]: at Socket.emit (node:events:514:28) Jan 08 18:54:44 volumio volumio[1205]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 08 18:54:44 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 08 18:54:44 volumio volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 18:54:45 volumio sudo[5186]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-08 18:53' Jan 08 18:54:45 volumio sudo[5186]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"