-- Logs begin at Thu 2025-12-04 04:32:10 CET, end at Tue 2025-12-09 21:04:31 CET. -- Dec 09 21:03:00 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:04 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:08 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:12 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:16 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:20 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:24 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:28 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 21:03:30 volumio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:03:30 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:30 volumio sudo[2662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:03:30 volumio sudo[2659]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:30 volumio sudo[2662]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:30 volumio sudo[2662]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:30 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 7 Dec 09 21:03:31 volumio sudo[2679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:03:31 volumio sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:31 volumio sudo[2682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:03:31 volumio sudo[2679]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:31 volumio sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:31 volumio sudo[2682]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:31 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 8 Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Dec 09 21:03:31 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:03:31 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:03:31 volumio volumio[1337]: info: Listing playlists Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 09 21:03:31 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 21:03:33 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:03:34 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:03:34 volumio volumio[1337]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:03:34 volumio volumio[1337]: info: CoreStateMachine::getQueue Dec 09 21:03:34 volumio volumio[1337]: info: CorePlayQueue::getQueue Dec 09 21:03:34 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: mnt/USB/D4A4-824F/MUSIC/Classique/George Gersshwin/Rhapsody in blue - I got rhythm de Freddy Kempf/04-Rhapsody_in_Blue F.Grofe piano and jazz band.flac Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: mnt/USB/D4A4-824F/MUSIC/Ludwig Von 88/New Orleans/08-Kaliman (jazz mix).flac Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: mnt/USB/D4A4-824F/MUSIC/connus/sanson/les moments importants/01-01-Veronique_Sanson-Amoureuse-320.mp3 Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:74AmfY2Fy2wxNfEZ2pmz4i Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:4kPvMwTuJxG1Yl0F7TcVqz Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:7ya5BEtCAz5VFzJf0M3elH Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:5FLkTtVTqbTrgZuQg6XMSe Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:2CX8idS7jHNK4HbGVCjOLU Dec 09 21:03:34 volumio volumio[1337]: info: Preloading song: spotify:track:1ASn1rCRTynDI2hh7xVJsY Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri mnt/USB/D4A4-824F/MUSIC/Classique/George Gersshwin/Rhapsody in blue - I got rhythm de Freddy Kempf/04-Rhapsody_in_Blue F.Grofe piano and jazz band.flac in service mpd Dec 09 21:03:34 volumio volumio[1337]: info: ALBUMART /albumart?cacheid=536&web=Andrew%20Litton%2C%20Conductor%20-%20Freddy%20Kempf%2C%20Performer%20-%20Bergen%20Philharmonic%20Orchestra%2C%20Orchestra%20-%20Ferde%20Grofe%2C%20Composer%20-%20George%20Gershwin%2C%20Composer/Freddy%20Kempf%20%3A%20Gershwin%3A%20Rhapsody%20in%20Blue%20-%20I%20Got%20Rhythm/extralarge&path=%2Fmnt%2FUSB%2FD4A4-824F%2FMUSIC%2FClassique%2FGeorge%20Gersshwin%2FRhapsody%20in%20blue%20-%20I%20got%20rhythm%20de%20Freddy%20Kempf%2F04-Rhapsody_in_Blue%20F.Grofe%20piano%20and%20jazz%20band.flac&metadata=false Dec 09 21:03:34 volumio volumio[1337]: info: URI /mnt/USB/D4A4-824F/MUSIC/Classique/George Gersshwin/Rhapsody in blue - I got rhythm de Freddy Kempf/04-Rhapsody_in_Blue F.Grofe piano and jazz band.flac Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri mnt/USB/D4A4-824F/MUSIC/Ludwig Von 88/New Orleans/08-Kaliman (jazz mix).flac in service mpd Dec 09 21:03:34 volumio volumio[1337]: info: ALBUMART /albumart?cacheid=536&web=Ludwig%20von%2088/New%20Orleans/extralarge&path=%2Fmnt%2FUSB%2FD4A4-824F%2FMUSIC%2FLudwig%20Von%2088%2FNew%20Orleans%2F08-Kaliman%20(jazz%20mix).flac&metadata=false Dec 09 21:03:34 volumio volumio[1337]: info: URI /mnt/USB/D4A4-824F/MUSIC/Ludwig Von 88/New Orleans/08-Kaliman (jazz mix).flac Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri mnt/USB/D4A4-824F/MUSIC/connus/sanson/les moments importants/01-01-Veronique_Sanson-Amoureuse-320.mp3 in service mpd Dec 09 21:03:34 volumio volumio[1337]: info: ALBUMART /albumart?cacheid=536&web=V%C3%A9ronique%20Sanson/Les%20moments%20importants%20-%20Best%20of%20V%C3%A9ronique%20Sanson/extralarge&path=%2Fmnt%2FUSB%2FD4A4-824F%2FMUSIC%2Fconnus%2Fsanson%2Fles%20moments%20importants%2F01-01-Veronique_Sanson-Amoureuse-320.mp3&metadata=false Dec 09 21:03:34 volumio volumio[1337]: info: URI /mnt/USB/D4A4-824F/MUSIC/connus/sanson/les moments importants/01-01-Veronique_Sanson-Amoureuse-320.mp3 Dec 09 21:03:34 volumio volumio[1337]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:74AmfY2Fy2wxNfEZ2pmz4i in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:74AmfY2Fy2wxNfEZ2pmz4i Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:4kPvMwTuJxG1Yl0F7TcVqz in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:4kPvMwTuJxG1Yl0F7TcVqz Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:7ya5BEtCAz5VFzJf0M3elH in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:7ya5BEtCAz5VFzJf0M3elH Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:5FLkTtVTqbTrgZuQg6XMSe in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:5FLkTtVTqbTrgZuQg6XMSe Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:74AmfY2Fy2wxNfEZ2pmz4i","service":"spop","name":"Piano Sonata No. 2 in B-Flat Minor, Op. 35: I. Grave. Doppio movimento","artist":"Frédéric Chopin","album":"Chopin: 24 Preludes, Piano Sonata No. 2, Etudes Op. 25","type":"song","duration":471,"albumart":"https://i.scdn.co/image/ab67616d0000b273f1a867c8baa0bbdc05ac0ac5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4kPvMwTuJxG1Yl0F7TcVqz","service":"spop","name":"Piano Sonata No. 2 in B-Flat Minor, Op. 35: II. Scherzo","artist":"Frédéric Chopin","album":"Chopin: 24 Preludes, Piano Sonata No. 2, Etudes Op. 25","type":"song","duration":449,"albumart":"https://i.scdn.co/image/ab67616d0000b273f1a867c8baa0bbdc05ac0ac5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:2CX8idS7jHNK4HbGVCjOLU in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:2CX8idS7jHNK4HbGVCjOLU Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ya5BEtCAz5VFzJf0M3elH","service":"spop","name":"Piano Sonata No. 2 in B-Flat Minor, Op. 35: III. Marche funèbre. Lento","artist":"Frédéric Chopin","album":"Chopin: 24 Preludes, Piano Sonata No. 2, Etudes Op. 25","type":"song","duration":578,"albumart":"https://i.scdn.co/image/ab67616d0000b273f1a867c8baa0bbdc05ac0ac5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:1ASn1rCRTynDI2hh7xVJsY in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:1ASn1rCRTynDI2hh7xVJsY Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5FLkTtVTqbTrgZuQg6XMSe","service":"spop","name":"Piano Sonata No. 2 in B-Flat Minor, Op. 35: IV. Finale. Presto. Sotto voce e legato","artist":"Frédéric Chopin","album":"Chopin: 24 Preludes, Piano Sonata No. 2, Etudes Op. 25","type":"song","duration":91,"albumart":"https://i.scdn.co/image/ab67616d0000b273f1a867c8baa0bbdc05ac0ac5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:34 volumio volumio[1337]: info: Exploding uri spotify:track:1ASn1rCRTynDI2hh7xVJsY in service spop Dec 09 21:03:34 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:track:1ASn1rCRTynDI2hh7xVJsY Dec 09 21:03:35 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2CX8idS7jHNK4HbGVCjOLU","service":"spop","name":"Ave Maria pour soprano","artist":"Franz Schubert","album":"Ave Maria : Airs & choeurs célèbres","type":"song","duration":313,"albumart":"https://i.scdn.co/image/ab67616d0000b273d77272dc8b4dabf2f5b7225d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:35 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ASn1rCRTynDI2hh7xVJsY","service":"spop","name":"12 Etudes, Op. 25: No. 3 in F Major, Allegro","artist":"Frédéric Chopin","album":"Chopin: Sonate No. 2, Op 35 & 12 études, Op. 25","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2734be2892a24e4c8ded884cd50","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:35 volumio volumio[1337]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ASn1rCRTynDI2hh7xVJsY","service":"spop","name":"12 Etudes, Op. 25: No. 3 in F Major, Allegro","artist":"Frédéric Chopin","album":"Chopin: Sonate No. 2, Op 35 & 12 études, Op. 25","type":"song","duration":101,"albumart":"https://i.scdn.co/image/ab67616d0000b2734be2892a24e4c8ded884cd50","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 09 21:03:35 volumio sudo[2699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:03:35 volumio sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:35 volumio sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:03:35 volumio sudo[2699]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:35 volumio sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:35 volumio sudo[2702]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:35 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 9 Dec 09 21:03:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Dec 09 21:03:36 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:03:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:03:36 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:03:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:03:36 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:03:36 volumio volumio[1337]: info: Listing playlists Dec 09 21:03:36 volumio volumio[1337]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:03:36 volumio volumio[1337]: info: CoreStateMachine::getQueue Dec 09 21:03:36 volumio volumio[1337]: info: CorePlayQueue::getQueue Dec 09 21:03:37 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 09 21:03:49 volumio volumio[1337]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 09 21:03:49 volumio volumio[1337]: info: Received Get System Version Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 09 21:03:49 volumio volumio[1337]: info: Received Get System Info Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 21:03:49 volumio volumio[1337]: info: Discovery: Getting this device information Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Dec 09 21:03:49 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:03:49 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 21:03:55 volumio sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 09 21:03:55 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:55 volumio sudo[2724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 09 21:03:55 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:55 volumio sudo[2724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 21:03:55 volumio sudo[2724]: pam_unix(sudo:session): session closed for user root Dec 09 21:03:55 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 9 Dec 09 21:03:55 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Dec 09 21:03:55 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:03:55 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:03:55 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:03:55 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:03:55 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:03:55 volumio volumio[1337]: info: Listing playlists Dec 09 21:03:55 volumio volumio[1337]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:03:55 volumio volumio[1337]: info: CoreStateMachine::getQueue Dec 09 21:03:55 volumio volumio[1337]: info: CorePlayQueue::getQueue Dec 09 21:03:59 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:03:59 volumio volumio[1337]: info: In handleBrowseUri, curUri=spotify Dec 09 21:04:00 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:00 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:00 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:00 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:03 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 09 21:04:03 volumio volumio[1337]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXdrln2UyZD7F Dec 09 21:04:04 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:04 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:04:04 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Dec 09 21:04:04 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:04 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:04 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Dec 09 21:04:04 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:04 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:04 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Dec 09 21:04:04 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Dec 09 21:04:04 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:04 volumio volumio[1337]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DXdrln2UyZD7F Dec 09 21:04:04 volumio volumio[1337]: info: Exploding uri spotify:playlist:37i9dQZF1DXdrln2UyZD7F in service spop Dec 09 21:04:04 volumio volumio[1337]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DXdrln2UyZD7F Dec 09 21:04:04 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:05 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:05 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Dec 09 21:04:05 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Dec 09 21:04:05 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::play index 0 Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::play index undefined Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:05 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:04:05 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:04:05 volumio volumio[1337]: info: CorePlayQueue::getTrack 0 Dec 09 21:04:05 volumio volumio[1337]: info: [1765310645823] ControllerSpotify::clearAddPlayTrack Dec 09 21:04:05 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:04:05 volumio go-librespot[2639]: time="2025-12-09T21:04:05+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:04:05 volumio go-librespot[2639]: time="2025-12-09T21:04:05+01:00" level=debug msg="loading track spotify:track:2Ocfrk1syda141tYv7v9lr (paused: false, position: 0ms)" Dec 09 21:04:05 volumio go-librespot[2639]: time="2025-12-09T21:04:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:04:05 volumio go-librespot[2639]: time="2025-12-09T21:04:05+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:04:05 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2Ocfrk1syda141tYv7v9lr","play_origin":"go-librespot"}} Dec 09 21:04:05 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2Ocfrk1syda141tYv7v9lr","play_origin":"go-librespot"}} Dec 09 21:04:05 volumio go-librespot[2639]: time="2025-12-09T21:04:05+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:2Ocfrk1syda141tYv7v9lr" Dec 09 21:04:05 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:04:07 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:07 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:07 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Dec 09 21:04:07 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ocfrk1syda141tYv7v9lr Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ocfrk1syda141tYv7v9lr Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ISJ3XBY0kqNVlCGXGSNtW Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0ISJ3XBY0kqNVlCGXGSNtW Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7KZw49EgpR1BxOAzy5WIXx Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:7KZw49EgpR1BxOAzy5WIXx Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4fibeTEFeBTurEsxadpS6Z Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:4fibeTEFeBTurEsxadpS6Z Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XOjhMErjEUvusZVpXA2kh Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0XOjhMErjEUvusZVpXA2kh Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Z39z6MnZqemvR9wnFgvix Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1Z39z6MnZqemvR9wnFgvix Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6ts9NFlODlQh6RgQ2N4O5i Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6ts9NFlODlQh6RgQ2N4O5i Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3WSyYBhLZRLbQo2tJgFvSR Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3WSyYBhLZRLbQo2tJgFvSR Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3UBItNVbFQiVC5hBQlBvnr Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3UBItNVbFQiVC5hBQlBvnr Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0KM2y796j63t5RHhvRUxld Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0KM2y796j63t5RHhvRUxld Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2RcbRwAFUxaiO3rszuEqsz Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2RcbRwAFUxaiO3rszuEqsz Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:583xVl0Sbj93uU4ceixgU8 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:583xVl0Sbj93uU4ceixgU8 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0f0k0OJ9VbaLHX4J1t2qUy Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0f0k0OJ9VbaLHX4J1t2qUy Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6h6Ccbgvhibl2XQ0SQNR70 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6h6Ccbgvhibl2XQ0SQNR70 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5LGqFIVBf3ZUeFBpc87CFS Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:5LGqFIVBf3ZUeFBpc87CFS Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5wznxkB792LjSRdbZ1J1zo Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:5wznxkB792LjSRdbZ1J1zo Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6DKM07xOL59GExqNgE8LFM Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6DKM07xOL59GExqNgE8LFM Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6VnpKLtfNH4Dk09YSGPSyR Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6VnpKLtfNH4Dk09YSGPSyR Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67LH2dzXF67Oi80yJaVYd7 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:67LH2dzXF67Oi80yJaVYd7 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2epbL7s3RFV81K5UhTgZje Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2epbL7s3RFV81K5UhTgZje Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3oUphdZVPyrsprZ8FgbmQS Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3oUphdZVPyrsprZ8FgbmQS Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73m3OYU7lG7irNbFjNipwF Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:73m3OYU7lG7irNbFjNipwF Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:69FtgMJrR2lfsBR97JSp4d Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:69FtgMJrR2lfsBR97JSp4d Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0smyCrJiibi2uwCiq5R1vj Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0smyCrJiibi2uwCiq5R1vj Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:36DztiNpSIH5ZHTwmqthHW Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:36DztiNpSIH5ZHTwmqthHW Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6ZLGthToczpvnL5Eoy6yrY Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6ZLGthToczpvnL5Eoy6yrY Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2NwXOuYLEbyhlQzDtoANul Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2NwXOuYLEbyhlQzDtoANul Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1vxw6aYJls2oq3gW0DujAo Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1vxw6aYJls2oq3gW0DujAo Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5o4XJKRxUK6nXhFLAIS33A Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:5o4XJKRxUK6nXhFLAIS33A Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6yHVyMdC08djbP86mdkP3G Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6yHVyMdC08djbP86mdkP3G Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3R7fjB38qajI6JR69y5k4e Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3R7fjB38qajI6JR69y5k4e Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0JtxeMlLho0MJLOrWpxLyN Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0JtxeMlLho0MJLOrWpxLyN Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3yUcJwYu7fXAfqMj9krY6l Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3yUcJwYu7fXAfqMj9krY6l Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4tKGFmENO69tZR9ahgZu48 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:4tKGFmENO69tZR9ahgZu48 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1My8oKezaFe1yuwowQKHuy Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1My8oKezaFe1yuwowQKHuy Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:11BAVEGi1ivJ6JWLqKUNrZ Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:11BAVEGi1ivJ6JWLqKUNrZ Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2MLHyLy5z5l5YRp7momlgw Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2MLHyLy5z5l5YRp7momlgw Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:244AvzGQ4Ksa5637JQu5Gy Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:244AvzGQ4Ksa5637JQu5Gy Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4poybupy26pTWPr9zY1i7h Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:4poybupy26pTWPr9zY1i7h Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GVUmCP00eSsqc4tzj1sDD Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:7GVUmCP00eSsqc4tzj1sDD Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5HdUMBc7AhPFhlPDZUpSwf Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:5HdUMBc7AhPFhlPDZUpSwf Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0A6sqSxlqml1wjQLjuM4BH Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0A6sqSxlqml1wjQLjuM4BH Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:58zsLZPvfflaiIbNWoA22O Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:58zsLZPvfflaiIbNWoA22O Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:267KRYX2EE0KGpiODvwzP8 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:267KRYX2EE0KGpiODvwzP8 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Tio0ZoDeSQnI7EBAqWer2 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:6Tio0ZoDeSQnI7EBAqWer2 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5m1x4QRgaKmK8cTkZQjGRr Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:5m1x4QRgaKmK8cTkZQjGRr Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:34xGLuxM0rkxhCVyMSqwJO Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:34xGLuxM0rkxhCVyMSqwJO Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1SKPmfSYaPsETbRHaiA18G Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1SKPmfSYaPsETbRHaiA18G Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7aQjPecQdIuNd1sz3KCDhD Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:7aQjPecQdIuNd1sz3KCDhD Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4SDWMFvYJKtK1QPhIOeZhd Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:4SDWMFvYJKtK1QPhIOeZhd Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1G391cbiT3v3Cywg8T7DM1 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1G391cbiT3v3Cywg8T7DM1 Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2aGw77pZqli064JiPuXYRi Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:2aGw77pZqli064JiPuXYRi Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:07K4GcCDSTiXUF5ssyTRUM Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:07K4GcCDSTiXUF5ssyTRUM Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1ELMCVbADOtZj8tYdS01nI Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:1ELMCVbADOtZj8tYdS01nI Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3dhjNA0jGA8vHBQ1VdD6vV Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:3dhjNA0jGA8vHBQ1VdD6vV Dec 09 21:04:07 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0Z7nGFVCLfixWctgePsRk9 Dec 09 21:04:07 volumio volumio[1337]: info: Using cached record of: spotify:track:0Z7nGFVCLfixWctgePsRk9 Dec 09 21:04:07 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Dec 09 21:04:07 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::play index 5 Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::play index undefined Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:07 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:04:07 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:07 volumio volumio[1337]: info: [1765310647057] ControllerSpotify::clearAddPlayTrack Dec 09 21:04:07 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:04:07 volumio go-librespot[2639]: time="2025-12-09T21:04:07+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:04:07 volumio go-librespot[2639]: time="2025-12-09T21:04:07+01:00" level=debug msg="loading track spotify:track:1Z39z6MnZqemvR9wnFgvix (paused: false, position: 0ms)" Dec 09 21:04:07 volumio go-librespot[2639]: time="2025-12-09T21:04:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:04:07 volumio go-librespot[2639]: time="2025-12-09T21:04:07+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:04:07 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:07 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:07 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:07 volumio go-librespot[2639]: time="2025-12-09T21:04:07+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:1Z39z6MnZqemvR9wnFgvix" Dec 09 21:04:07 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:04:08 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:08 volumio volumio[1337]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::ClearQueue Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::clearPlayQueue Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:08 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::addQueueItems Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::addQueueItems Dec 09 21:04:08 volumio volumio[1337]: info: Preload queue cleared Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2Ocfrk1syda141tYv7v9lr Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2Ocfrk1syda141tYv7v9lr Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0ISJ3XBY0kqNVlCGXGSNtW Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0ISJ3XBY0kqNVlCGXGSNtW Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7KZw49EgpR1BxOAzy5WIXx Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:7KZw49EgpR1BxOAzy5WIXx Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4fibeTEFeBTurEsxadpS6Z Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:4fibeTEFeBTurEsxadpS6Z Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0XOjhMErjEUvusZVpXA2kh Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0XOjhMErjEUvusZVpXA2kh Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1Z39z6MnZqemvR9wnFgvix Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1Z39z6MnZqemvR9wnFgvix Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6ts9NFlODlQh6RgQ2N4O5i Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6ts9NFlODlQh6RgQ2N4O5i Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3WSyYBhLZRLbQo2tJgFvSR Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3WSyYBhLZRLbQo2tJgFvSR Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3UBItNVbFQiVC5hBQlBvnr Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3UBItNVbFQiVC5hBQlBvnr Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0KM2y796j63t5RHhvRUxld Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0KM2y796j63t5RHhvRUxld Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2RcbRwAFUxaiO3rszuEqsz Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2RcbRwAFUxaiO3rszuEqsz Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:583xVl0Sbj93uU4ceixgU8 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:583xVl0Sbj93uU4ceixgU8 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0f0k0OJ9VbaLHX4J1t2qUy Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0f0k0OJ9VbaLHX4J1t2qUy Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6h6Ccbgvhibl2XQ0SQNR70 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6h6Ccbgvhibl2XQ0SQNR70 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5LGqFIVBf3ZUeFBpc87CFS Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:5LGqFIVBf3ZUeFBpc87CFS Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5wznxkB792LjSRdbZ1J1zo Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:5wznxkB792LjSRdbZ1J1zo Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6DKM07xOL59GExqNgE8LFM Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6DKM07xOL59GExqNgE8LFM Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6VnpKLtfNH4Dk09YSGPSyR Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6VnpKLtfNH4Dk09YSGPSyR Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:67LH2dzXF67Oi80yJaVYd7 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:67LH2dzXF67Oi80yJaVYd7 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2epbL7s3RFV81K5UhTgZje Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2epbL7s3RFV81K5UhTgZje Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3oUphdZVPyrsprZ8FgbmQS Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3oUphdZVPyrsprZ8FgbmQS Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:73m3OYU7lG7irNbFjNipwF Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:73m3OYU7lG7irNbFjNipwF Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:69FtgMJrR2lfsBR97JSp4d Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:69FtgMJrR2lfsBR97JSp4d Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0smyCrJiibi2uwCiq5R1vj Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0smyCrJiibi2uwCiq5R1vj Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:36DztiNpSIH5ZHTwmqthHW Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:36DztiNpSIH5ZHTwmqthHW Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6ZLGthToczpvnL5Eoy6yrY Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6ZLGthToczpvnL5Eoy6yrY Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2NwXOuYLEbyhlQzDtoANul Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2NwXOuYLEbyhlQzDtoANul Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1vxw6aYJls2oq3gW0DujAo Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1vxw6aYJls2oq3gW0DujAo Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5o4XJKRxUK6nXhFLAIS33A Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:5o4XJKRxUK6nXhFLAIS33A Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6yHVyMdC08djbP86mdkP3G Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6yHVyMdC08djbP86mdkP3G Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3R7fjB38qajI6JR69y5k4e Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3R7fjB38qajI6JR69y5k4e Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0JtxeMlLho0MJLOrWpxLyN Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0JtxeMlLho0MJLOrWpxLyN Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3yUcJwYu7fXAfqMj9krY6l Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3yUcJwYu7fXAfqMj9krY6l Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4tKGFmENO69tZR9ahgZu48 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:4tKGFmENO69tZR9ahgZu48 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1My8oKezaFe1yuwowQKHuy Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1My8oKezaFe1yuwowQKHuy Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6QgjcU0zLnzq5OrUoSZ3OK Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:11BAVEGi1ivJ6JWLqKUNrZ Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:11BAVEGi1ivJ6JWLqKUNrZ Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2MLHyLy5z5l5YRp7momlgw Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2MLHyLy5z5l5YRp7momlgw Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:244AvzGQ4Ksa5637JQu5Gy Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:244AvzGQ4Ksa5637JQu5Gy Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4poybupy26pTWPr9zY1i7h Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:4poybupy26pTWPr9zY1i7h Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7GVUmCP00eSsqc4tzj1sDD Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:7GVUmCP00eSsqc4tzj1sDD Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5HdUMBc7AhPFhlPDZUpSwf Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:5HdUMBc7AhPFhlPDZUpSwf Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0A6sqSxlqml1wjQLjuM4BH Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0A6sqSxlqml1wjQLjuM4BH Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:58zsLZPvfflaiIbNWoA22O Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:58zsLZPvfflaiIbNWoA22O Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:267KRYX2EE0KGpiODvwzP8 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:267KRYX2EE0KGpiODvwzP8 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:6Tio0ZoDeSQnI7EBAqWer2 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:6Tio0ZoDeSQnI7EBAqWer2 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:5m1x4QRgaKmK8cTkZQjGRr Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:5m1x4QRgaKmK8cTkZQjGRr Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:34xGLuxM0rkxhCVyMSqwJO Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:34xGLuxM0rkxhCVyMSqwJO Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1SKPmfSYaPsETbRHaiA18G Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1SKPmfSYaPsETbRHaiA18G Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1qDrWA6lyx8cLECdZE7TV7 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:7aQjPecQdIuNd1sz3KCDhD Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:7aQjPecQdIuNd1sz3KCDhD Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:4SDWMFvYJKtK1QPhIOeZhd Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:4SDWMFvYJKtK1QPhIOeZhd Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1G391cbiT3v3Cywg8T7DM1 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1G391cbiT3v3Cywg8T7DM1 Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:2aGw77pZqli064JiPuXYRi Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:2aGw77pZqli064JiPuXYRi Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:07K4GcCDSTiXUF5ssyTRUM Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:07K4GcCDSTiXUF5ssyTRUM Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:1ELMCVbADOtZj8tYdS01nI Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:1ELMCVbADOtZj8tYdS01nI Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:3dhjNA0jGA8vHBQ1VdD6vV Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:3dhjNA0jGA8vHBQ1VdD6vV Dec 09 21:04:08 volumio volumio[1337]: info: Adding Item to queue: spotify:track:0Z7nGFVCLfixWctgePsRk9 Dec 09 21:04:08 volumio volumio[1337]: info: Using cached record of: spotify:track:0Z7nGFVCLfixWctgePsRk9 Dec 09 21:04:08 volumio volumio[1337]: info: CoreCommandRouter::volumioPushQueue Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::saveQueue Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::updateTrackBlock Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::getTrackBlock Dec 09 21:04:08 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::play index 5 Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::stop Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::play index undefined Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:08 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:04:08 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:08 volumio volumio[1337]: info: [1765310648481] ControllerSpotify::clearAddPlayTrack Dec 09 21:04:08 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:04:08 volumio go-librespot[2639]: time="2025-12-09T21:04:08+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:04:08 volumio go-librespot[2639]: time="2025-12-09T21:04:08+01:00" level=debug msg="loading track spotify:track:1Z39z6MnZqemvR9wnFgvix (paused: false, position: 0ms)" Dec 09 21:04:08 volumio go-librespot[2639]: time="2025-12-09T21:04:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:04:08 volumio go-librespot[2639]: time="2025-12-09T21:04:08+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:04:08 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:08 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:08 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:08 volumio go-librespot[2639]: time="2025-12-09T21:04:08+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:1Z39z6MnZqemvR9wnFgvix" Dec 09 21:04:08 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:04:09 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Dec 09 21:04:09 volumio volumio[1337]: info: CoreStateMachine::play index undefined Dec 09 21:04:09 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:09 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:09 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:04:09 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:09 volumio volumio[1337]: info: [1765310649993] ControllerSpotify::clearAddPlayTrack Dec 09 21:04:09 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:04:10 volumio go-librespot[2639]: time="2025-12-09T21:04:10+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:04:10 volumio go-librespot[2639]: time="2025-12-09T21:04:10+01:00" level=debug msg="loading track spotify:track:1Z39z6MnZqemvR9wnFgvix (paused: false, position: 0ms)" Dec 09 21:04:10 volumio go-librespot[2639]: time="2025-12-09T21:04:10+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:04:10 volumio go-librespot[2639]: time="2025-12-09T21:04:10+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:04:10 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:10 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:10 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:10 volumio go-librespot[2639]: time="2025-12-09T21:04:10+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:1Z39z6MnZqemvR9wnFgvix" Dec 09 21:04:10 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:04:26 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Dec 09 21:04:26 volumio volumio[1337]: info: Playing Remote Device: http://192.168.1.103 Dec 09 21:04:26 volumio volumio[1337]: info: Dec 09 21:04:26 volumio volumio[1337]: ---------------------------- Client requests Volumio play Dec 09 21:04:26 volumio volumio[1337]: info: CoreCommandRouter::volumioPlay Dec 09 21:04:26 volumio volumio[1337]: info: CoreStateMachine::play index undefined Dec 09 21:04:26 volumio volumio[1337]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 09 21:04:26 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:26 volumio volumio[1337]: info: CoreStateMachine::startPlaybackTimer Dec 09 21:04:26 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:26 volumio volumio[1337]: info: [1765310666458] ControllerSpotify::clearAddPlayTrack Dec 09 21:04:26 volumio volumio[1337]: info: Sending Spotify command with payload to local API: /player/play Dec 09 21:04:26 volumio volumio[1337]: info: Done playing: Dec 09 21:04:26 volumio go-librespot[2639]: time="2025-12-09T21:04:26+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Dec 09 21:04:26 volumio go-librespot[2639]: time="2025-12-09T21:04:26+01:00" level=debug msg="loading track spotify:track:1Z39z6MnZqemvR9wnFgvix (paused: false, position: 0ms)" Dec 09 21:04:26 volumio go-librespot[2639]: time="2025-12-09T21:04:26+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 09 21:04:26 volumio go-librespot[2639]: time="2025-12-09T21:04:26+01:00" level=trace msg="emitting websocket event: will_play" Dec 09 21:04:26 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:26 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:26 volumio volumio[1337]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1Z39z6MnZqemvR9wnFgvix","play_origin":"go-librespot"}} Dec 09 21:04:26 volumio go-librespot[2639]: time="2025-12-09T21:04:26+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: no playable formats for spotify:track:1Z39z6MnZqemvR9wnFgvix" Dec 09 21:04:26 volumio volumio[1337]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 09 21:04:27 volumio volumio[1337]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.102 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:145.0) Gecko/20100101 Firefox/145.0 Total Clients: 9 Dec 09 21:04:28 volumio volumio[1337]: info: CoreCommandRouter::volumioGetState Dec 09 21:04:28 volumio volumio[1337]: info: CorePlayQueue::getTrack 5 Dec 09 21:04:28 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 21:04:28 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 09 21:04:28 volumio volumio[1337]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 21:04:28 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 21:04:28 volumio volumio[1337]: info: Listing playlists Dec 09 21:04:28 volumio volumio[1337]: info: CoreCommandRouter::volumioGetQueue Dec 09 21:04:28 volumio volumio[1337]: info: CoreStateMachine::getQueue Dec 09 21:04:28 volumio volumio[1337]: info: CorePlayQueue::getQueue Dec 09 21:04:30 volumio volumio[1337]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Dec 09 21:04:30 volumio volumio[1337]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Dec 09 21:04:30 volumio volumio[1337]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:04:30 volumio volumio[1337]: TypeError: Cannot read property 'then' of undefined Dec 09 21:04:30 volumio volumio[1337]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:364:9) Dec 09 21:04:30 volumio volumio[1337]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2248:30) Dec 09 21:04:30 volumio volumio[1337]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Dec 09 21:04:30 volumio volumio[1337]: at Socket.emit (events.js:400:28) Dec 09 21:04:30 volumio volumio[1337]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 09 21:04:30 volumio volumio[1337]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 09 21:04:30 volumio volumio[1337]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 21:04:31 volumio sudo[2752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 21:03 Dec 09 21:04:31 volumio sudo[2752]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:57:46 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="42c06b905df70635f414371e374efaf3"