-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-08 15:02:28 CEST. -- Jun 08 15:01:00 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:00 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:01 volumio go-librespot[1241]: time="2025-06-08T15:01:01+02:00" level=debug msg="fetched chunk 8/17, size: 524288" uri="spotify:track:6aYXpFUSjl9YCYcn13khsh" Jun 08 15:01:10 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:10 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:10 volumio volumio[789]: info: Listing playlists Jun 08 15:01:10 volumio volumio[789]: info: Listing playlists Jun 08 15:01:11 volumio go-librespot[1241]: time="2025-06-08T15:01:11+02:00" level=trace msg="sent dealer ping" Jun 08 15:01:11 volumio go-librespot[1241]: time="2025-06-08T15:01:11+02:00" level=trace msg="received dealer pong" Jun 08 15:01:14 volumio go-librespot[1241]: time="2025-06-08T15:01:14+02:00" level=debug msg="fetched chunk 9/17, size: 524288" uri="spotify:track:6aYXpFUSjl9YCYcn13khsh" Jun 08 15:01:15 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 15:01:15 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:01:15 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root Jun 08 15:01:15 volumio sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 15:01:15 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:01:15 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Jun 08 15:01:15 volumio volumio[789]: verbose: New Socket.io Connection to 192.168.188.67 from 192.168.188.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:15 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 15:01:15 volumio volumio[789]: info: Received Get System Info Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:01:15 volumio volumio[789]: info: Discovery: Getting this device information Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:15 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:15 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:15 volumio volumio[789]: info: Listing playlists Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 15:01:15 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 15:01:15 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:01:15 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root Jun 08 15:01:15 volumio sudo[2034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 15:01:15 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:01:15 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 15:01:15 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Jun 08 15:01:16 volumio volumio[789]: verbose: New Socket.io Connection to 192.168.188.67 from 192.168.188.39 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:16 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 15:01:16 volumio volumio[789]: info: Received Get System Info Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:01:16 volumio volumio[789]: info: Discovery: Getting this device information Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:16 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:16 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:16 volumio volumio[789]: info: Listing playlists Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 15:01:16 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 15:01:17 volumio volumio[789]: info: VolumeController::SetAlsaVolume0 Jun 08 15:01:17 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:17 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:17 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:17 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:17 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:17 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:17 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:01:18 volumio volumio[789]: info: Received Get System Info Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:01:18 volumio volumio[789]: info: Discovery: Getting this device information Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:18 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:01:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 15:01:20 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:20 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio volumio[789]: info: Jun 08 15:01:22 volumio volumio[789]: ---------------------------- Client requests Volumio pause Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::volumioPause Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::pause Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::servicePause Jun 08 15:01:22 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::servicePause Jun 08 15:01:22 volumio volumio[789]: info: Spotify Received pause Jun 08 15:01:22 volumio volumio[789]: SPOTIFY: SPOTIFY PAUSE Jun 08 15:01:22 volumio volumio[789]: SPOTIFY: {"status":"play","position":2,"title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d0000b273c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","codec":"ogg","seek":81068,"duration":225,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jun 08 15:01:22 volumio volumio[789]: info: Sending Spotify command to local API: /player/pause Jun 08 15:01:22 volumio go-librespot[1241]: time="2025-06-08T15:01:22+02:00" level=debug msg="pause track at 85827ms" Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:22 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio go-librespot[1241]: time="2025-06-08T15:01:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:22 volumio go-librespot[1241]: time="2025-06-08T15:01:22+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:22 volumio go-librespot[1241]: time="2025-06-08T15:01:22+02:00" level=trace msg="emitting websocket event: paused" Jun 08 15:01:22 volumio volumio[789]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","play_origin":"go-librespot"}} Jun 08 15:01:22 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:22 volumio volumio[789]: SPOTIFY: {"status":"pause","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:22 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio volumio[789]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:22 volumio volumio[789]: verbose: CURRENT POSITION 2 Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::syncState stateService pause Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus pause Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:22 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:22 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:01:22 volumio volumio[789]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:01:22 volumio volumio[789]: info: Received Get System Info Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:01:22 volumio volumio[789]: info: Discovery: Getting this device information Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:22 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:22 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:01:24 volumio volumio[789]: info: Jun 08 15:01:24 volumio volumio[789]: ---------------------------- Client requests Volumio play Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::volumioPlay Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::play index undefined Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::startPlaybackTimer Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: info: Spotify Resume Jun 08 15:01:24 volumio volumio[789]: info: Sending Spotify command to local API: /player/resume Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=trace msg="seek to 85827ms (diff: 85ms, samples: 3784970, bytes: 3438265)" uri="spotify:track:6aYXpFUSjl9YCYcn13khsh" Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=debug msg="resume track at 85742ms" Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=trace msg="scheduling prefetch in 110s" Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:24 volumio go-librespot[1241]: time="2025-06-08T15:01:24+02:00" level=trace msg="emitting websocket event: playing" Jun 08 15:01:24 volumio volumio[789]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","play_origin":"go-librespot"}} Jun 08 15:01:24 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:24 volumio volumio[789]: SPOTIFY: {"status":"play","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:24 volumio volumio[789]: verbose: CURRENT POSITION 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::syncState stateService play Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus pause Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:24 volumio volumio[789]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 08 15:01:24 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:24 volumio volumio[789]: SPOTIFY: {"status":"play","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":85000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:24 volumio volumio[789]: verbose: CURRENT POSITION 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::syncState stateService play Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus play Jun 08 15:01:24 volumio volumio[789]: info: Received an update from plugin. extracting info from payload Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:24 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:24 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:24 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:25 volumio volumio[789]: info: Jun 08 15:01:25 volumio volumio[789]: ---------------------------- Client requests Volumio play Jun 08 15:01:25 volumio volumio[789]: info: CoreCommandRouter::volumioPlay Jun 08 15:01:25 volumio volumio[789]: info: CoreStateMachine::play index undefined Jun 08 15:01:25 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:01:25 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:25 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:25 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:25 volumio volumio[789]: info: Listing playlists Jun 08 15:01:25 volumio volumio[789]: info: Listing playlists Jun 08 15:01:26 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 08 15:01:27 volumio volumio[789]: info: Jun 08 15:01:27 volumio volumio[789]: ---------------------------- Client requests Volumio pause Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::volumioPause Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::pause Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::servicePause Jun 08 15:01:27 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::servicePause Jun 08 15:01:27 volumio volumio[789]: info: Spotify Received pause Jun 08 15:01:27 volumio volumio[789]: SPOTIFY: SPOTIFY PAUSE Jun 08 15:01:27 volumio volumio[789]: SPOTIFY: {"status":"play","position":2,"title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d0000b273c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","codec":"ogg","seek":85000,"duration":225,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jun 08 15:01:27 volumio volumio[789]: info: Sending Spotify command to local API: /player/pause Jun 08 15:01:27 volumio go-librespot[1241]: time="2025-06-08T15:01:27+02:00" level=debug msg="pause track at 88552ms" Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:27 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:27 volumio go-librespot[1241]: time="2025-06-08T15:01:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:27 volumio go-librespot[1241]: time="2025-06-08T15:01:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:27 volumio go-librespot[1241]: time="2025-06-08T15:01:27+02:00" level=trace msg="emitting websocket event: paused" Jun 08 15:01:27 volumio volumio[789]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","play_origin":"go-librespot"}} Jun 08 15:01:27 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:27 volumio volumio[789]: SPOTIFY: {"status":"pause","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":87000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:27 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:27 volumio volumio[789]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d00001e02c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","seek":87000,"duration":225,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:27 volumio volumio[789]: verbose: CURRENT POSITION 2 Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::syncState stateService pause Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus pause Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:27 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:27 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:27 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:01:27 volumio volumio[789]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 08 15:01:30 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:30 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:31 volumio volumio[789]: info: Jun 08 15:01:31 volumio volumio[789]: ---------------------------- Client requests Volumio next Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::volumioNext Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::next Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::stop Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::updateTrackBlock Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrackBlock Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::serviceStop Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 2 Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::serviceStop Jun 08 15:01:31 volumio volumio[789]: info: Spotify Stop Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: SPOTIFY STOP Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: {"status":"pause","position":2,"title":"So sind wir","artist":"Böhse Onkelz","album":"Kneipenterroristen","albumart":"https://i.scdn.co/image/ab67616d0000b273c9187ebcdf4862ab127b7c1a","uri":"spotify:track:6aYXpFUSjl9YCYcn13khsh","trackType":"spotify","codec":"ogg","seek":87753,"duration":225,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":0,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jun 08 15:01:31 volumio volumio[789]: info: Sending Spotify command to local API: /player/pause Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::play index undefined Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::startPlaybackTimer Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:31 volumio volumio[789]: info: [1749387691399] ControllerSpotify::clearAddPlayTrack Jun 08 15:01:31 volumio volumio[789]: info: Sending Spotify command with payload to local API: /player/play Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::updateTrackBlock Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrackBlock Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="resolved context of track" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=trace msg="emitting websocket event: will_play" Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","play_origin":"go-librespot"}} Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="selected format OGG_VORBIS_320 (1b4b7413e0bd98e7b9651cb9f4b00d462560c087)" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="requested aes key for file 1b4b7413e0bd98e7b9651cb9f4b00d462560c087, gid: 0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="fetched first chunk of 15, total size is 7710908 bytes" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=info msg="loaded track \"Gehasst, Verdammt, Vergöttert\" (paused: false, position: 0ms, duration: 186000ms, prefetched: false)" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=trace msg="scheduling prefetch in 156s" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=trace msg="emitting websocket event: metadata" Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","name":"Gehasst, Verdammt, Vergöttert","artist_names":["Böhse Onkelz"],"album_name":"Heilige Lieder","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","position":0,"duration":186000,"release_date":"year:1992","track_number":10,"disc_number":1}} Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=trace msg="emitting websocket event: playing" Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="pause track at 119ms" Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","play_origin":"go-librespot"}} Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:31 volumio volumio[789]: SPOTIFY: {"status":"play","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:31 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:31 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:31 volumio volumio[789]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:31 volumio volumio[789]: verbose: CURRENT POSITION 3 Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::syncState stateService play Jun 08 15:01:31 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus stop Jun 08 15:01:31 volumio go-librespot[1241]: time="2025-06-08T15:01:31+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:32 volumio go-librespot[1241]: time="2025-06-08T15:01:32+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:0XTpATXWLPGPvBhTSQjb6M" Jun 08 15:01:32 volumio go-librespot[1241]: time="2025-06-08T15:01:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:32 volumio go-librespot[1241]: time="2025-06-08T15:01:32+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 08 15:01:32 volumio go-librespot[1241]: time="2025-06-08T15:01:32+02:00" level=trace msg="emitting websocket event: paused" Jun 08 15:01:32 volumio volumio[789]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","play_origin":"go-librespot"}} Jun 08 15:01:32 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:32 volumio volumio[789]: SPOTIFY: {"status":"pause","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:32 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:32 volumio volumio[789]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:32 volumio volumio[789]: verbose: CURRENT POSITION 3 Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::syncState stateService pause Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus play Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:32 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:32 volumio volumio[789]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 08 15:01:32 volumio volumio[789]: SPOTIFY: PUSH STATE SPOTIFY Jun 08 15:01:32 volumio volumio[789]: SPOTIFY: {"status":"pause","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:32 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:32 volumio volumio[789]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Gehasst, Verdammt, Vergöttert","artist":"Böhse Onkelz","album":"Heilige Lieder","albumart":"https://i.scdn.co/image/ab67616d00001e02bedbba3a1ecf60665478c136","uri":"spotify:track:0XTpATXWLPGPvBhTSQjb6M","trackType":"spotify","seek":0,"duration":186,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jun 08 15:01:32 volumio volumio[789]: verbose: CURRENT POSITION 3 Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::syncState stateService pause Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::syncState currentStatus play Jun 08 15:01:32 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:32 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:32 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:34 volumio volumio[789]: info: Jun 08 15:01:34 volumio volumio[789]: ---------------------------- Client requests Volumio play Jun 08 15:01:34 volumio volumio[789]: info: CoreCommandRouter::volumioPlay Jun 08 15:01:34 volumio volumio[789]: info: CoreStateMachine::play index undefined Jun 08 15:01:34 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:01:34 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:34 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:34 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 15:01:35 volumio volumio[789]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 08 15:01:35 volumio volumio[789]: info: Received Get System Version Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 15:01:35 volumio volumio[789]: info: Received Get System Info Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 15:01:35 volumio volumio[789]: info: Discovery: Getting this device information Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:35 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:35 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 15:01:37 volumio volumio[789]: info: Jun 08 15:01:37 volumio volumio[789]: ---------------------------- Client requests Volume 39 Jun 08 15:01:37 volumio volumio[789]: info: VolumeController::SetAlsaVolume39 Jun 08 15:01:37 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 100 Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 39 Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 08 15:01:37 volumio volumio[789]: info: Setting Spotify Volume from Volumio: 39 Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: Jun 08 15:01:37 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:37 volumio volumio[789]: info: Jun 08 15:01:37 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:37 volumio volumio[789]: info: Jun 08 15:01:37 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:37 volumio volumio[789]: info: sendMpdCommand status took 5 milliseconds Jun 08 15:01:37 volumio volumio[789]: info: sendMpdCommand status took 4 milliseconds Jun 08 15:01:37 volumio volumio[789]: info: sendMpdCommand status took 3 milliseconds Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:37 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:37 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:37 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:37 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:37 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:37 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:37 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:37 volumio volumio[789]: info: ------------------------------ 42ms Jun 08 15:01:37 volumio volumio[789]: info: ------------------------------ 41ms Jun 08 15:01:37 volumio volumio[789]: error: Upnp client error: Error: This socket has been ended by the other party Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 08 15:01:37 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Jun 08 15:01:39 volumio volumio[789]: SPOTIFY: SETTING SPOTIFY VOLUME 39 Jun 08 15:01:39 volumio volumio[789]: info: Sending Spotify command with payload to local API: /player/volume Jun 08 15:01:39 volumio go-librespot[1241]: time="2025-06-08T15:01:39+02:00" level=debug msg="update volume to 25558/65535" Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio go-librespot[1241]: time="2025-06-08T15:01:40+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 08 15:01:40 volumio go-librespot[1241]: time="2025-06-08T15:01:40+02:00" level=trace msg="emitting websocket event: volume" Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: received: {"type":"volume","data":{"value":39,"max":100}} Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: RECEIVED SPOTIFY VOLUME 39 Jun 08 15:01:40 volumio volumio[789]: info: Jun 08 15:01:40 volumio volumio[789]: ---------------------------- Client requests Volume + Jun 08 15:01:40 volumio volumio[789]: info: VolumeController::SetAlsaVolume+ Jun 08 15:01:40 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 39 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: Jun 08 15:01:40 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:40 volumio volumio[789]: info: Jun 08 15:01:40 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:40 volumio volumio[789]: info: Jun 08 15:01:40 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:40 volumio volumio[789]: info: sendMpdCommand status took 6 milliseconds Jun 08 15:01:40 volumio volumio[789]: info: sendMpdCommand status took 4 milliseconds Jun 08 15:01:40 volumio volumio[789]: info: sendMpdCommand status took 3 milliseconds Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:40 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:40 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:40 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:40 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:40 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:40 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:40 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:40 volumio volumio[789]: info: ------------------------------ 49ms Jun 08 15:01:40 volumio volumio[789]: info: ------------------------------ 48ms Jun 08 15:01:40 volumio volumio[789]: info: ------------------------------ 46ms Jun 08 15:01:40 volumio volumio[789]: info: Listing playlists Jun 08 15:01:40 volumio volumio[789]: info: Listing playlists Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 39 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 39 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 39 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 40 Jun 08 15:01:40 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 08 15:01:41 volumio go-librespot[1241]: time="2025-06-08T15:01:41+02:00" level=trace msg="sent dealer ping" Jun 08 15:01:41 volumio go-librespot[1241]: time="2025-06-08T15:01:41+02:00" level=trace msg="received dealer pong" Jun 08 15:01:41 volumio volumio[789]: info: Jun 08 15:01:41 volumio volumio[789]: ---------------------------- Client requests Volume mute Jun 08 15:01:41 volumio volumio[789]: info: VolumeController::SetAlsaVolumemute Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: SPOTIFY VOLUME 39 Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: VOLUMIO VOLUME 0 Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 08 15:01:41 volumio volumio[789]: info: Setting Spotify Volume from Volumio: 0 Jun 08 15:01:41 volumio volumio[789]: info: Jun 08 15:01:41 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:41 volumio volumio[789]: info: Jun 08 15:01:41 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:41 volumio volumio[789]: info: Jun 08 15:01:41 volumio volumio[789]: ---------------------------- MPD announces state update: mixer Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::getState Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::sendMpdCommand status Jun 08 15:01:41 volumio volumio[789]: info: sendMpdCommand status took 4 milliseconds Jun 08 15:01:41 volumio volumio[789]: info: sendMpdCommand status took 2 milliseconds Jun 08 15:01:41 volumio volumio[789]: info: sendMpdCommand status took 1 milliseconds Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:41 volumio volumio[789]: verbose: ControllerMpd::parseState Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:41 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:41 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:41 volumio volumio[789]: info: ControllerMpd::pushState Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::servicePushState Jun 08 15:01:41 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:01:41 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:41 volumio volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current spop Received mpd Jun 08 15:01:41 volumio volumio[789]: info: ------------------------------ 41ms Jun 08 15:01:41 volumio volumio[789]: info: ------------------------------ 41ms Jun 08 15:01:41 volumio volumio[789]: info: ------------------------------ 41ms Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 08 15:01:41 volumio volumio[789]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 08 15:01:42 volumio volumio[789]: SPOTIFY: SETTING SPOTIFY VOLUME 0 Jun 08 15:01:42 volumio volumio[789]: info: Sending Spotify command with payload to local API: /player/volume Jun 08 15:01:42 volumio go-librespot[1241]: time="2025-06-08T15:01:42+02:00" level=debug msg="update volume to 0/65535" Jun 08 15:01:44 volumio go-librespot[1241]: time="2025-06-08T15:01:44+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 08 15:01:44 volumio go-librespot[1241]: time="2025-06-08T15:01:44+02:00" level=trace msg="emitting websocket event: volume" Jun 08 15:01:44 volumio volumio[789]: SPOTIFY: received: {"type":"volume","data":{"value":0,"max":100}} Jun 08 15:01:44 volumio volumio[789]: SPOTIFY: RECEIVED SPOTIFY VOLUME 0 Jun 08 15:01:46 volumio volumio[789]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 15:01:46 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:01:50 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:01:50 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:01:55 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:55 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:55 volumio volumio[789]: info: Retrieving Cloud Streaming UI Jun 08 15:01:56 volumio volumio[789]: info: Getting Tidal Cloud Configuration Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:56 volumio volumio[789]: info: Getting Qobuz Cloud Configuration Jun 08 15:01:56 volumio volumio[789]: info: Asking plugin for UI Config Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:56 volumio volumio[789]: info: Getting Spotify Cloud Configuration Jun 08 15:01:56 volumio volumio[789]: info: Asking plugin for UI Config Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:56 volumio volumio[789]: info: Saving Spotify Acccount Jun 08 15:01:56 volumio volumio[789]: info: Got Tidal Cloud Configuration Jun 08 15:01:56 volumio volumio[789]: info: Got it Jun 08 15:01:56 volumio volumio[789]: info: Got it Jun 08 15:01:56 volumio volumio[789]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 15:01:56 volumio volumio[789]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::volumioGetBrowseSources Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 08 15:01:56 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 08 15:02:00 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:02:00 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:02:00 volumio volumio[789]: info: Listing playlists Jun 08 15:02:00 volumio volumio[789]: info: Listing playlists Jun 08 15:02:00 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 08 15:02:04 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 08 15:02:08 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 08 15:02:10 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:02:10 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:02:11 volumio go-librespot[1241]: time="2025-06-08T15:02:11+02:00" level=trace msg="sent dealer ping" Jun 08 15:02:11 volumio go-librespot[1241]: time="2025-06-08T15:02:11+02:00" level=trace msg="received dealer pong" Jun 08 15:02:12 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 08 15:02:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 15:02:18 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 15:02:20 volumio volumio[789]: info: CoreCommandRouter::volumioGetState Jun 08 15:02:20 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:02:20 volumio volumio[789]: info: Listing playlists Jun 08 15:02:20 volumio volumio[789]: info: Listing playlists Jun 08 15:02:26 volumio go-librespot[1241]: time="2025-06-08T15:02:26+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jun 08 15:02:26 volumio volumio[789]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jun 08 15:02:26 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 15:02:26 volumio volumio[789]: info: Disabling plugin spop Jun 08 15:02:26 volumio volumio[789]: info: Done. Jun 08 15:02:26 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Jun 08 15:02:26 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 15:02:26 volumio volumio[789]: info: Connection to go-librespot Websocket closed Jun 08 15:02:26 volumio systemd[1]: Stopping go-librespot Daemon... Jun 08 15:02:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jun 08 15:02:26 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Jun 08 15:02:26 volumio systemd[1]: Stopped go-librespot Daemon. Jun 08 15:02:26 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Jun 08 15:02:28 volumio volumio[789]: info: CoreCommandRouter::volumioNext Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::next Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::stop Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::stPlaybackTimer Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::updateTrackBlock Jun 08 15:02:28 volumio volumio[789]: info: CorePlayQueue::getTrackBlock Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::pushState Jun 08 15:02:28 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:02:28 volumio volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 15:02:28 volumio volumio[789]: info: CoreCommandRouter::volumioPushState Jun 08 15:02:28 volumio volumio[789]: info: CoreStateMachine::serviceStop Jun 08 15:02:28 volumio volumio[789]: info: CorePlayQueue::getTrack 3 Jun 08 15:02:28 volumio volumio[789]: info: CoreCommandRouter::serviceStop Jun 08 15:02:28 volumio volumio[789]: error: WARNING: No stop method for service spop Jun 08 15:02:28 volumio volumio[789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 15:02:28 volumio volumio[789]: TypeError: Cannot read property 'then' of undefined Jun 08 15:02:28 volumio volumio[789]: at CoreStateMachine.next (/volumio/app/statemachine.js:1177:9) Jun 08 15:02:28 volumio volumio[789]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Jun 08 15:02:28 volumio volumio[789]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Jun 08 15:02:28 volumio volumio[789]: at Socket.emit (events.js:315:20) Jun 08 15:02:28 volumio volumio[789]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 08 15:02:28 volumio volumio[789]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 08 15:02:28 volumio volumio[789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 15:02:28 volumio sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 15:01 Jun 08 15:02:28 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"