Oct 02 08:50:01 music-hq-1 volumio[1232]: info: Enabling plugin spop Oct 02 08:50:01 music-hq-1 volumio[1232]: info: Loading plugin "spop"... Oct 02 08:50:02 music-hq-1 volumio[1232]: info: PLUGIN START: spop Oct 02 08:50:02 music-hq-1 volumio[1232]: info: Creating Spotify config file Oct 02 08:50:02 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:50:02 music-hq-1 volumio[1232]: info: Done. Oct 02 08:50:02 music-hq-1 volumio[1232]: info: Spotify config file written Oct 02 08:50:02 music-hq-1 sudo[5440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 02 08:50:02 music-hq-1 sudo[5440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:50:02 music-hq-1 volumio[1232]: info: No need to fix Spotify hosts Oct 02 08:50:02 music-hq-1 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 08:50:02 music-hq-1 systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 02 08:50:02 music-hq-1 volumio[1232]: info: New Spotify access tokenBQCyPw6HsT... Oct 02 08:50:02 music-hq-1 volumio[1232]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 02 08:50:02 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:02 music-hq-1 go-librespot[5442]: go-librespot daemon starting... Oct 02 08:50:02 music-hq-1 sudo[5440]: pam_unix(sudo:session): session closed for user root Oct 02 08:50:02 music-hq-1 volumio[1232]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 08:50:02 music-hq-1 volumio[1232]: SPOTIFY: User informations: {"account_id":"KUfTcWPnuN","country":"DE","display_name":"hydenrider","email":"ludwig.heidenreiter@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/316q52raq2nink3ruo6ucdrbvtnu"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/316q52raq2nink3ruo6ucdrbvtnu","id":"316q52raq2nink3ruo6ucdrbvtnu","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee851254403e8440f4e107d59d3c","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b821254403e8440f4e107d59d3c","width":64}],"product":"premium","type":"user","uri":"spotify:user:316q52raq2nink3ruo6ucdrbvtnu"} Oct 02 08:50:02 music-hq-1 volumio[1232]: info: Spotify Successfully logged in Oct 02 08:50:02 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 08:50:02 music-hq-1 volumio[1232]: info: [1790923802777] CoreMusicLibrary::Adding element Spotify Oct 02 08:50:02 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 08:50:02 music-hq-1 volumio[1232]: Cannot find translation for source SoundCloud Oct 02 08:50:02 music-hq-1 volumio[1232]: Cannot find translation for source YouTube2 Oct 02 08:50:02 music-hq-1 volumio[1232]: Cannot find translation for source YouTube Music Oct 02 08:50:02 music-hq-1 volumio[1232]: Cannot find translation for source Spotify Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=debug msg="app state loaded" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::ClearQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::clearPlayQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPlay Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::play index 2 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::play index undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 2 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::startPlaybackTimer Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 2 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=237081482 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 2 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 2 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Stopping playback by current service... Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioStop Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Setting ourselves as the current service... Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-000139191507-8zdqf3-t500x500.jpg","uri":"soundcloud/track@trackId=237081482","seek":0,"duration":0,"service":"soundcloud","artist":"Pöte","album":"SoundCloud Track","name":"Johnny Cash - Folsom Prison Blues (Szijarto Peter remix)","title":"Johnny Cash - Folsom Prison Blues (Szijarto Peter remix)","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=237081482","title":"Johnny Cash - Folsom Prison Blues (Szijarto Peter remix)","artist":"Pöte","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-000139191507-8zdqf3-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=info msg="zeroconf server listening on port 34093" Oct 02 08:50:03 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:03+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=237081482","title":"Johnny Cash - Folsom Prison Blues (Szijarto Peter remix)","artist":"Pöte","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-000139191507-8zdqf3-t500x500.jpg","trackType":"aac","duration":187,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] adaptive demux: Changing stream format Unknown -> MP4 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b17ffea0] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 1 error: Cancellation (0x8) Oct 02 08:50:03 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 3 error: Cancellation (0x8) Oct 02 08:50:04 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 5 error: Cancellation (0x8) Oct 02 08:50:04 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 7 error: Cancellation (0x8) Oct 02 08:50:05 music-hq-1 volumio[1232]: info: go-librespot daemon successfully initialized Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPause Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreStateMachine::pause Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreStateMachine::stPlaybackTimer Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreStateMachine::servicePause Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePause Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:05 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Push Volumio state: {"status":"pause","uri":"soundcloud/track@trackId=237081482","title":"Johnny Cash - Folsom Prison Blues (Szijarto Peter remix)","artist":"Pöte","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-000139191507-8zdqf3-t500x500.jpg","trackType":"aac","duration":187,"samplerate":"160 kbps","service":"soundcloud","seek":1000,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:05 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::ClearQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::serviceStop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::serviceStop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::stPlaybackTimer Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::clearPlayQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPlay Oct 02 08:50:08 music-hq-1 volumio[1232]: verbose: UNSET VOLATILE: Service: soundcloud Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Volatile state unset, stopping playback (if any)... Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":1000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 2 Oct 02 08:50:08 music-hq-1 volumio[1232]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":1000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:08 music-hq-1 volumio[1232]: verbose: CURRENT POSITION 2 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::syncState stateService stop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::syncState currentStatus stop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: No code Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::play index 0 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::play index undefined Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 0 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CoreStateMachine::startPlaybackTimer Oct 02 08:50:08 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 0 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=326293297 Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:50:08 music-hq-1 go-librespot[5443]: time="2026-10-02T08:50:08+02:00" level=debug msg="new websocket client" Oct 02 08:50:08 music-hq-1 volumio[1232]: info: Connection to go-librespot Websocket established Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 9 error: Cancellation (0x8) Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) [b3555150] http demux error: local stream 1 error: Cancellation (0x8) Oct 02 08:50:08 music-hq-1 volumio[1232]: info: [soundcloud] [vlc] (PID: 1781) Player status "stopped" - unsetting ourselves as current service... Oct 02 08:50:11 music-hq-1 volumio[1232]: info: Getting Spotify volume Oct 02 08:50:11 music-hq-1 volumio[1232]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 02 08:50:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:11 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 0 Oct 02 08:50:11 music-hq-1 volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 08:50:11 music-hq-1 volumio[1232]: SPOTIFY: SPOTIFY VOLUME undefined Oct 02 08:50:11 music-hq-1 volumio[1232]: SPOTIFY: VOLUMIO VOLUME 34 Oct 02 08:50:11 music-hq-1 volumio[1232]: info: Aligning Spotify Volume to Volumio Volume Oct 02 08:50:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:11 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 0 Oct 02 08:50:11 music-hq-1 volumio[1232]: info: Setting Spotify Volume from Volumio: 34 Oct 02 08:50:13 music-hq-1 volumio[1232]: SPOTIFY: SETTING SPOTIFY VOLUME 34 Oct 02 08:50:13 music-hq-1 volumio[1232]: info: Sending Spotify command with payload to local API: /player/volume Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::ClearQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::clearPlayQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=326293297 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256316759 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=237081482 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=256317636 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1086122236 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2014714935 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=67441016 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=2211330833 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=1781109426 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPlay Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::play index 8 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::addQueueItems Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::addQueueItems Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=933163732 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'/track@trackId=394831146 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Adding Item to queue: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: Using cached record of: soundcloud/tracks@search=Folsom%20Prison%20Blues@title=SoundCloud%20tracks%20matching%20'Folsom%20Prison%20Blues'@pageRef:o=%7B%22pageToken%22%3A%22%22%2C%22pageOffset%22%3A11%7D@noExplode=1 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::saveQueue Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::play index undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::updateTrackBlock Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrackBlock Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 8 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::startPlaybackTimer Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 8 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1781109426 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 8 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CorePlayQueue::getTrack 8 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Stopping playback by current service... Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioStop Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::stop Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Setting ourselves as the current service... Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Send IPC command: {"command":["set_property","loop-file","no"],"request_id":51} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Got response for command #51: {"request_id":51,"error":"success"} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-KD7fX5PCtaeW5KOJ-BMdVtw-t500x500.jpg","uri":"soundcloud/track@trackId=1781109426","seek":0,"duration":0,"service":"soundcloud","artist":"Alberto Argentino","album":"SoundCloud Track","name":"Johnny Cash | Folsom Prison Blues | Wrath of Man | Soundtrack.mp3","title":"Johnny Cash | Folsom Prison Blues | Wrath of Man | Soundtrack.mp3","trackType":"mp3","samplerate":"128 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Send IPC command: {"command":["loadfile","https://cf-media.sndcdn.com/kZhKJfqg6eBP.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20va1poS0pmcWc2ZUJQLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzkwOTI0MTE5fX19XX0_&Signature=fCIB~1O0-YlGf60MQVUBmXMeQgEE~XcEJUsxCBza6Yo85v7DTgb2viAzYhBopskZdu7TwX4wIzexeTP5uBOKGhFZm4sGWuQk1v4FCkux46sVjz2VGwCjkg3ZUZjDSlYqNFh7sfF2EZs-ApRh6QtXekyLzjnvYlCjTPpWUVsDVJDWlMEJpSpC4WuxDXj-5siVhgxg1VW5AddgjrhnXYlrIxpO94FsXEB4GlKGi7F3QWIqGyxg1jyQ8d1kN6I064K9ewJTeJW5zjfgzRwdpp7VexZTwCa7t2HzsSzesvEOA37dLcj56weSpy-GcxEUUUKLZgfOx5ubqgHRovQBfMaUiw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","replace"],"request_id":52} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Got response for command #52: {"data":{"playlist_entry_id":11},"request_id":52,"error":"success"} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Status update -> property-change events: [{"subscriptionId":3,"prop":"idle-active","data":false},{"subscriptionId":8,"prop":"media-title","data":"kZhKJfqg6eBP.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLW1lZGlhLnNuZGNkbi5jb20va1poS0pmcWc2ZUJQLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzkwOTI0MTE5fX19XX0_&Signature=fCIB~1O0-YlGf60MQVUBmXMeQgEE~XcEJUsxCBza6Yo85v7DTgb2viAzYhBopskZdu7TwX4wIzexeTP5uBOKGhFZm4sGWuQk1v4FCkux46sVjz2VGwCjkg3ZUZjDSlYqNFh7sfF2EZs-ApRh6QtXekyLzjnvYlCjTPpWUVsDVJDWlMEJpSpC4WuxDXj-5siVhgxg1VW5AddgjrhnXYlrIxpO94FsXEB4GlKGi7F3QWIqGyxg1jyQ8d1kN6I064K9ewJTeJW5zjfgzRwdpp7VexZTwCa7t2HzsSzesvEOA37dLcj56weSpy-GcxEUUUKLZgfOx5ubqgHRovQBfMaUiw__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ"}] Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Send IPC command: {"command":["set_property","pause",false],"request_id":53} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1781109426","title":"Johnny Cash | Folsom Prison Blues | Wrath of Man | Soundtrack.mp3","artist":"Alberto Argentino","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-KD7fX5PCtaeW5KOJ-BMdVtw-t500x500.jpg","trackType":"mp3","samplerate":"128 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:15 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:15 music-hq-1 volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 08:50:15 music-hq-1 volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 08:50:15 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Got response for command #53: {"request_id":53,"error":"success"} Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) (+) Audio --aid=1 (mp3 2ch 44100Hz) Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Status update -> property-change events: [{"subscriptionId":2,"prop":"duration","data":183.745306},{"subscriptionId":7,"prop":"audio-codec-name","data":"mp3"},{"subscriptionId":9,"prop":"metadata","data":{"encoder":"LAME3.100"}}] Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1781109426","title":"Johnny Cash | Folsom Prison Blues | Wrath of Man | Soundtrack.mp3","artist":"Alberto Argentino","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-KD7fX5PCtaeW5KOJ-BMdVtw-t500x500.jpg","trackType":"mp3","duration":183.745306,"samplerate":"128 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:17 music-hq-1 volumio5-onboarding[1471]: time=2026-10-02T08:50:17.444+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 183.745306 into Go struct field State.duration of type int" Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params","data":{"samplerate":44100,"channel-count":2,"channels":"stereo","hr-channels":"stereo","format":"floatp"}}] Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1781109426","title":"Johnny Cash | Folsom Prison Blues | Wrath of Man | Soundtrack.mp3","artist":"Alberto Argentino","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-KD7fX5PCtaeW5KOJ-BMdVtw-t500x500.jpg","trackType":"mp3","duration":183.745306,"samplerate":"128 kbps","channels":2,"service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":34,"mute":false,"disableVolumeControl":false} Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::servicePushState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreStateMachine::pushState Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 08:50:17 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioPushState Oct 02 08:50:17 music-hq-1 volumio5-onboarding[1471]: time=2026-10-02T08:50:17.453+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 183.745306 into Go struct field State.duration of type int" Oct 02 08:50:17 music-hq-1 volumio[1232]: info: [soundcloud] [mpv] (PID: 4184) AO: [alsa] 44100Hz stereo 2ch float Oct 02 08:50:17 music-hq-1 volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 08:50:17 music-hq-1 volumio[1232]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 08:50:27 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: soundcloud , handleBrowseUri Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] browseUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457 Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preload queue cleared Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317636@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317707@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317662@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317671@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317659@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317668@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317714@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317734@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317653@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317649@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317760@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317670@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317642@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317660@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317701@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Preloading song: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317809@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317636@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317636@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317636@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317707@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317707@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317707@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317662@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317662@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317662@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317671@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317671@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317671@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317659@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317659@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317659@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317668@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317668@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317668@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317714@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317714@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:27 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317714@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317734@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317734@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317734@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317653@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317653@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317653@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317649@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317649@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317649@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317760@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317760@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317760@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317670@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317670@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317670@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317642@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317642@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317642@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317660@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317660@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317660@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317701@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317701@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317701@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: Exploding uri soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317809@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D in service soundcloud Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] explodeUri: soundcloud/albums@search=Folsom%20Prison%20Blues@combinedSearch=1@title=SoundCloud%20albums%20matching%20'Folsom%20Prison%20Blues'/albums@albumId=249541457/track@trackId=256317809@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:28 music-hq-1 volumio[1232]: info: [soundcloud] getTrackUri(): soundcloud/track@trackId=256317809@origin:o=%7B%22type%22%3A%22album%22%2C%22albumId%22%3A249541457%7D Oct 02 08:50:51 music-hq-1 volumio[1232]: info: Received OAUTH Data Oct 02 08:50:51 music-hq-1 volumio[1232]: info: Executing Spotify Oauth Login Oct 02 08:50:51 music-hq-1 volumio[1232]: info: Saving Spotify Refresh Token Oct 02 08:50:51 music-hq-1 volumio[1232]: info: New Spotify access tokenBQC16L4vOV... Oct 02 08:50:51 music-hq-1 volumio[1232]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 02 08:50:52 music-hq-1 sudo[5554]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 08:50:52 music-hq-1 sudo[5554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:50:52 music-hq-1 volumio[1232]: SPOTIFY: User informations: {"account_id":"KUfTcWPnuN","country":"DE","display_name":"hydenrider","email":"ludwig.heidenreiter@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/316q52raq2nink3ruo6ucdrbvtnu"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/316q52raq2nink3ruo6ucdrbvtnu","id":"316q52raq2nink3ruo6ucdrbvtnu","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee851254403e8440f4e107d59d3c","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b821254403e8440f4e107d59d3c","width":64}],"product":"premium","type":"user","uri":"spotify:user:316q52raq2nink3ruo6ucdrbvtnu"} Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Creating Spotify config file Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:50:52 music-hq-1 sudo[5554]: pam_unix(sudo:session): session closed for user root Oct 02 08:50:52 music-hq-1 sudo[5556]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 08:50:52 music-hq-1 sudo[5556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Spotify config file written Oct 02 08:50:52 music-hq-1 sudo[5556]: pam_unix(sudo:session): session closed for user root Oct 02 08:50:52 music-hq-1 sudo[5560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 02 08:50:52 music-hq-1 sudo[5560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:50:52 music-hq-1 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 02 08:50:52 music-hq-1 systemd[1]: go-librespot-daemon.service: Killing process 5455 (go-librespot) with signal SIGKILL. Oct 02 08:50:52 music-hq-1 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 02 08:50:52 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Connection to go-librespot Websocket closed Oct 02 08:50:52 music-hq-1 volumio[1232]: verbose: New Socket.io Connection to 10.243.243.20 from 10.243.243.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/153.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 02 08:50:52 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:52 music-hq-1 go-librespot[5562]: go-librespot daemon starting... Oct 02 08:50:52 music-hq-1 sudo[5560]: pam_unix(sudo:session): session closed for user root Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 08:50:52 music-hq-1 volumio[1232]: info: New Spotify access tokenBQAUQ88gGZ... Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="app state loaded" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:50:52 music-hq-1 volumio[1232]: SPOTIFY: User informations: {"account_id":"KUfTcWPnuN","country":"DE","display_name":"hydenrider","email":"ludwig.heidenreiter@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/316q52raq2nink3ruo6ucdrbvtnu"},"followers":{"href":null,"total":1},"href":"https://api.spotify.com/v1/users/316q52raq2nink3ruo6ucdrbvtnu","id":"316q52raq2nink3ruo6ucdrbvtnu","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee851254403e8440f4e107d59d3c","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b821254403e8440f4e107d59d3c","width":64}],"product":"premium","type":"user","uri":"spotify:user:316q52raq2nink3ruo6ucdrbvtnu"} Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Spotify Successfully logged in Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 08:50:52 music-hq-1 volumio[1232]: info: [1790923852340] CoreMusicLibrary::Adding element Spotify Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 08:50:52 music-hq-1 volumio[1232]: Cannot find translation for source SoundCloud Oct 02 08:50:52 music-hq-1 volumio[1232]: Cannot find translation for source YouTube2 Oct 02 08:50:52 music-hq-1 volumio[1232]: Cannot find translation for source YouTube Music Oct 02 08:50:52 music-hq-1 volumio[1232]: Cannot find translation for source Spotify Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Received Get System Info Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Discovery: Getting this device information Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:52 music-hq-1 volumio[1232]: info: Listing playlists Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 08:50:52 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=info msg="zeroconf server listening on port 39101" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="obtained new client token: AAGgvKCKcqsACWc2kE6ozGa/mDc7+dJ+XC3UokgTTjGA7nU2D7UnRE4PPfPqODeVg0m/1Tfb0iKMT+aDtQIUL+jFErwGSbJi6MSNZ44CZT7pPwUq3y+WyTdfPtR/XZYFLOJLakn57JCQKtCpXaA6e2Yeb3D0esClEF28WGbtyRdhQmdo3CkNjyFgqUMjIO8hnV7r2V4rEijc4nTJ0nCp4SioXX0Yzo44I8t5L3A55Xi9eIi/R0cxQ2cu" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="completed keyexchange" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=debug msg="completed challenge" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:50:52 music-hq-1 go-librespot[5564]: time="2026-10-02T08:50:52+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:50:52 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:50:52 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:50:53 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 08:50:54 music-hq-1 volumio[1232]: info: Received Get System Info Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:50:54 music-hq-1 volumio[1232]: info: Discovery: Getting this device information Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 08:50:54 music-hq-1 volumio[1232]: info: Received Get System Info Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:50:54 music-hq-1 volumio[1232]: info: Discovery: Getting this device information Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:50:54 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:50:55 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:50:55 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:50:55 music-hq-1 volumio[1232]: info: go-librespot daemon successfully initialized Oct 02 08:50:55 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 02 08:50:55 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:55 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:55 music-hq-1 go-librespot[5574]: go-librespot daemon starting... Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="app state loaded" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=info msg="zeroconf server listening on port 37205" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="obtained new client token: AAFzkZbID9O4io8KqfatNeiE/PbxJGtvnLUVBJDCG875gdxvqRcTn8V7TrRb4GQ7+Ua6pgWTaqRrICo+L6ilERocO/TyB+JFg5TDM8y/iltinuE9dN/n4MBbcxuZcnVKHbR91bLRics0hOVbbFu8uboLrw3N+GqiRF0inUqwbCe79MLq/qBPK4bHmrOynuncEfGdUo1ecdpHH2cejhtotjispt1pT71b6XN3zWYZe/tKkpexCh5OPOjT" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="completed keyexchange" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=debug msg="completed challenge" Oct 02 08:50:55 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:55+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:50:56 music-hq-1 go-librespot[5575]: time="2026-10-02T08:50:56+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:50:56 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:50:56 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:50:58 music-hq-1 volumio[1232]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Oct 02 08:50:58 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Creating Spotify config file Oct 02 08:50:58 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 08:50:58 music-hq-1 volumio[1232]: info: Spotify config file written Oct 02 08:50:58 music-hq-1 sudo[5601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 02 08:50:58 music-hq-1 sudo[5601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:50:58 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:58 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:50:58 music-hq-1 go-librespot[5603]: go-librespot daemon starting... Oct 02 08:50:58 music-hq-1 sudo[5601]: pam_unix(sudo:session): session closed for user root Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=debug msg="app state loaded" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=info msg="zeroconf server listening on port 34853" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:50:58 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:58+02:00" level=debug msg="obtained new client token: AAHtwi4EFQKrooBthgqMiJfTXE4L7aFknUJeG8WfHRhG7vzrShh+J9JjIq5RK6pCKyIweMmh5HgYI2+CCx24GOgrgOkcUVDBLmZBMMONlu1Ot/JjReOc2Jwacc8rY0DofiWvWtClSw67yWPKu80oednVQr4N8aXtB8ymr0Q/r2x3q2Ii9jTIj4piIy8/fvOU0n3TyBFtJlEhdshFXpYsaPrCjUtK2DyF2fTnUNlXlVObm6Sq8RmQlfEJ" Oct 02 08:50:59 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:59+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:50:59 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:59+02:00" level=debug msg="completed keyexchange" Oct 02 08:50:59 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:59+02:00" level=debug msg="completed challenge" Oct 02 08:50:59 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:59+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:50:59 music-hq-1 go-librespot[5604]: time="2026-10-02T08:50:59+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:50:59 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:50:59 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:01 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:01 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:01 music-hq-1 volumio[1232]: info: go-librespot daemon successfully initialized Oct 02 08:51:02 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Oct 02 08:51:02 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:02 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:02 music-hq-1 go-librespot[5613]: go-librespot daemon starting... Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="app state loaded" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=info msg="zeroconf server listening on port 42347" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="obtained new client token: AAH5N6fZVGfr1qh6z1M4/9ziXtX3oSY00MPDmpqNbAEr3czjXP9tfWN3ZTk+pY7Zc6DGmzKoY02SC+4kufc/f33m5fd3YI9q9/EcADvP0njUk7wiBVqPzBZEpti+Y4LTE+MheOwCi0ZyPfIbvFzH/dvZGrfbipG/vuqjOGy5LA93LIud2lsbDlpeQptH8Gxn3048SdSZi/9+7+EE1nb+H5Cy4MddGTg130vMlMoBt7PK2owaPBuiZb8J" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=debug msg="completed challenge" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:02 music-hq-1 go-librespot[5614]: time="2026-10-02T08:51:02+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:02 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:02 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:03 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 08:51:04 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:04 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:04 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:04 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:05 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Oct 02 08:51:05 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:05 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:05 music-hq-1 go-librespot[5623]: go-librespot daemon starting... Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="app state loaded" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=info msg="zeroconf server listening on port 43907" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="obtained new client token: AAEFNd/3Bb2IJD4GxipNNYfd0S5hHmI27mm7Sk/hMMsKxUq3KjnmfZk9FQsr2j6yxp3YzxwwBUiwAK+9Go0dyL3yuuh4HDqxdrUSDuY8Uf3IL/chJVgfb/knTjyM18PUyJdlxs6gyFJ8uUwNR5AgNV7PbFXVr+sXl9s6D3fdAA9NzSV1+nEqgWIbWule4yzWCSNZ/PsE2DkWsIhUq1E3r5V8nuTJ+tfD4S6uJJQhFc8SzadYp963NQQo" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:51:05 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:05+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:06 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:06+02:00" level=debug msg="completed challenge" Oct 02 08:51:06 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:06+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:06 music-hq-1 go-librespot[5624]: time="2026-10-02T08:51:06+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:06 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:06 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:07 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:07 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:09 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Oct 02 08:51:09 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:09 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:09 music-hq-1 go-librespot[5647]: go-librespot daemon starting... Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="app state loaded" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=info msg="zeroconf server listening on port 40151" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="obtained new client token: AAF5H1g2oiQBOCGFShJu6hLQ5hxELCxKfwt5zejWpNstpaUBpy98NdxPqx1abKRkb34j3/TySH7v1geEbUaNAxpEGQMse6gRMQmIg+bagjV3aS3fOLkAbv0SOxyj8ZR2TS7Y3Ah9O9xc2JFNls6q0jQQBMQMcA5R1tfJYPiFcQzy9Mk1tzm0vqBtAjltQy0uu8DPxNBiSd4srhuYOZrHWgFDN72hnO/TBUDnH5p0wnat3fv1/A3vDHCJ" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=debug msg="completed challenge" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:09 music-hq-1 go-librespot[5648]: time="2026-10-02T08:51:09+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:09 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:09 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:10 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:10 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 08:51:11 music-hq-1 volumio[1232]: info: Received Get System Version Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 08:51:11 music-hq-1 volumio[1232]: info: Received Get System Info Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 08:51:11 music-hq-1 volumio[1232]: info: Discovery: Getting this device information Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::volumioGetState Oct 02 08:51:11 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 08:51:12 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Oct 02 08:51:12 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:12 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:12 music-hq-1 go-librespot[5659]: go-librespot daemon starting... Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="app state loaded" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=info msg="zeroconf server listening on port 37131" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="obtained new client token: AAGwKZziGc1TStdOVv0ZLUKzim68HhdNR2HiAydAcRvlgtQrSsATIIF31lc0ti6QeubwPQwqax1Bc/i696bPiC4+cHPtx7QQ7ggFihPF/MxDpVffW7hn7mB2bqz7twfaL5fE4P8vntlCZ+EK6BkT1+wISBesMzCzSJabBOtS/sRRq05O0nmTTnd10YrYn47sZyqivl8fmrOy/eGlHxxLbKYQtasft5iXGoxDWPC9vRtwU4TBkvjNDTOT" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:12 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:12+02:00" level=debug msg="completed challenge" Oct 02 08:51:13 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:13+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:13 music-hq-1 go-librespot[5660]: time="2026-10-02T08:51:13+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:13 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:13 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:13 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:13 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:16 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Oct 02 08:51:16 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:16 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:16 music-hq-1 go-librespot[5669]: go-librespot daemon starting... Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="app state loaded" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=info msg="zeroconf server listening on port 46835" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="obtained new client token: AAGNmxKD359YrazyrQHYYpe4a/kQBH0IjrTYt8usS8ddBqnUYjkUiVdzNfnFg1x4CxVO95gRu1O6YblVPGFrwb1JfVIOBgpR08uJq7xdPihnZcWHahvI5QPbUkapADsG0OHn4A6D2WA/2V+/phq2nZZCr4KEoI4VrOS/7NEYd3B59D2XShBrWhWlV5pEoPFOZTuu0mx8CaSr3NyMvJku/a+gaB4m2wD8lwbNFquUEKiUoFuDYXAXjNMS" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=debug msg="completed challenge" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:16 music-hq-1 go-librespot[5670]: time="2026-10-02T08:51:16+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:16 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:16 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:16 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:16 music-hq-1 volumio[1232]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:19 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Oct 02 08:51:19 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:19 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:19 music-hq-1 go-librespot[5694]: go-librespot daemon starting... Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="app state loaded" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 02 08:51:19 music-hq-1 volumio[1232]: info: Initializing connection to go-librespot Websocket Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="new websocket client" Oct 02 08:51:19 music-hq-1 volumio[1232]: info: Connection to go-librespot Websocket established Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=info msg="zeroconf server listening on port 34425" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="obtained new client token: AAEtP6CatGkWh4sZ7/GU9vDSsc4XO13JKjyGospxEmC3u3Jm4lGL92HdNoaF+5nmVikZG+hstvR92CiOvc7wwRguGBlI0If0U6OqYyCfvD16+Ngz6221PEjTxKdmo0WqGioHnbWAO4hAN5I1AwlyWfrgOWiLMWoL30OXpEBpKYznbWbDjEwYM0lP1Nowok9xIzdRkVr2XDfOaqDrEtHocIdIJqXaTARjB9jwIbIZ172b76co22Gfh4v7" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="completed keyexchange" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=debug msg="completed challenge" Oct 02 08:51:19 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:19+02:00" level=info msg="authenticated AP" username="31************************nu" Oct 02 08:51:20 music-hq-1 go-librespot[5695]: time="2026-10-02T08:51:20+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Oct 02 08:51:20 music-hq-1 volumio[1232]: info: Connection to go-librespot Websocket closed Oct 02 08:51:20 music-hq-1 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 02 08:51:20 music-hq-1 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 08:51:21 music-hq-1 volumio[1232]: Searching plugin music_service/soundcloud Oct 02 08:51:21 music-hq-1 volumio[1232]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Oct 02 08:51:22 music-hq-1 volumio[1232]: info: Getting Spotify volume Oct 02 08:51:22 music-hq-1 volumio[1232]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 08:51:22 music-hq-1 volumio[1232]: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 02 08:51:22 music-hq-1 volumio[1232]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 02 08:51:22 music-hq-1 volumio[1232]: errno: -111, Oct 02 08:51:22 music-hq-1 volumio[1232]: code: 'ECONNREFUSED', Oct 02 08:51:22 music-hq-1 volumio[1232]: syscall: 'connect', Oct 02 08:51:22 music-hq-1 volumio[1232]: address: '127.0.0.1', Oct 02 08:51:22 music-hq-1 volumio[1232]: port: 9879, Oct 02 08:51:22 music-hq-1 volumio[1232]: response: undefined Oct 02 08:51:22 music-hq-1 volumio[1232]: } Oct 02 08:51:22 music-hq-1 volumio[1232]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 08:51:23 music-hq-1 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Oct 02 08:51:23 music-hq-1 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:23 music-hq-1 sudo[5719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 08:50' Oct 02 08:51:23 music-hq-1 sudo[5719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 08:51:23 music-hq-1 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 02 08:51:23 music-hq-1 go-librespot[5716]: go-librespot daemon starting... Oct 02 08:51:23 music-hq-1 go-librespot[5721]: time="2026-10-02T08:51:23+02:00" level=info msg="running go-librespot 0.7.1" Oct 02 08:51:23 music-hq-1 go-librespot[5721]: time="2026-10-02T08:51:23+02:00" level=debug msg="app state loaded" Oct 02 08:51:23 music-hq-1 go-librespot[5721]: time="2026-10-02T08:51:23+02:00" level=info msg="api server listening on 127.0.0.1:9879" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"