Dec 15 23:34:00 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:00 volumio volumio[1533]: info: Preloading song: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:2BwO5K8Q7EPAJSGze3AAh9 in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:42VUCXerQ5qTr4Qp6PhKo4 in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:3EMfeKtNK4q4k3sVjwg5Yy in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:5B3GZOZYXNzWpUXQC42hxZ in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2BwO5K8Q7EPAJSGze3AAh9","service":"spop","name":"Manchild","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:6wkYQMrFfb0huLB5Qn19Iv in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:4SRShYMtFIGgnOU7iBicMH in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:1PdUyYYw9sGU0yhLrpeHKt in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3EMfeKtNK4q4k3sVjwg5Yy","service":"spop","name":"My Man on Willpower","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","service":"spop","name":"Tears","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:0je57Uq5eTk1wrPzn9sWbl in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:1qyw5wSUkEvH8DtaCdx7Lg in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4SRShYMtFIGgnOU7iBicMH","service":"spop","name":"Nobody’s Son","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:21IVPfi81m6ywNgOvqTj1i in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5B3GZOZYXNzWpUXQC42hxZ","service":"spop","name":"Sugar Talking","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6wkYQMrFfb0huLB5Qn19Iv","service":"spop","name":"We Almost Broke Up Again Last Night","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:25jgQBxuUkGDdCG1WGKKN9 in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0je57Uq5eTk1wrPzn9sWbl","service":"spop","name":"When Did You Get Hot?","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1PdUyYYw9sGU0yhLrpeHKt","service":"spop","name":"Never Getting Laid","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:00 volumio volumio[1533]: info: Exploding uri spotify:track:4tVVmShMF2PYdLqcejrsy2 in service spop Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:00 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qyw5wSUkEvH8DtaCdx7Lg","service":"spop","name":"Go Go Juice","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:01 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:21IVPfi81m6ywNgOvqTj1i","service":"spop","name":"Don’t Worry I’ll Make You Worry","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:01 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4tVVmShMF2PYdLqcejrsy2","service":"spop","name":"Goodbye","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":225,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:01 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:25jgQBxuUkGDdCG1WGKKN9","service":"spop","name":"House Tour","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1863bf95557ea7f357c4947","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:01 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:01 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:01 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:01 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:01 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:01 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:01 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:01 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:01 volumio volumio[1533]: info: Using cached record of: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:01 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:01 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:01 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:01 volumio volumio[1533]: info: [1765809241230] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:01 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:01 volumio go-librespot[1852]: time="2025-12-15T23:34:01+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:01 volumio go-librespot[1852]: time="2025-12-15T23:34:01+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:01 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:04 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:04 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:04 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:04 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:04 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:04 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:04 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:04 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:04 volumio volumio[1533]: info: Using cached record of: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:04 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:04 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:04 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:04 volumio volumio[1533]: info: [1765809244154] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:04 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:04 volumio go-librespot[1852]: time="2025-12-15T23:34:04+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:05 volumio go-librespot[1852]: time="2025-12-15T23:34:05+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:05 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:07 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:07 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:34:07 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:34:07 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:08 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:08 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:08 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:08 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:08 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:08 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:08 volumio volumio[1533]: info: Using cached record of: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:08 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:08 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:08 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:08 volumio volumio[1533]: info: [1765809248011] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:08 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:08 volumio go-librespot[1852]: time="2025-12-15T23:34:08+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:08 volumio go-librespot[1852]: time="2025-12-15T23:34:08+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:08 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:09 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:09 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:09 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:09 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:2BwO5K8Q7EPAJSGze3AAh9 Dec 15 23:34:09 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:09 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:09 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:3EMfeKtNK4q4k3sVjwg5Yy Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:5B3GZOZYXNzWpUXQC42hxZ Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:6wkYQMrFfb0huLB5Qn19Iv Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:4SRShYMtFIGgnOU7iBicMH Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:1PdUyYYw9sGU0yhLrpeHKt Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:0je57Uq5eTk1wrPzn9sWbl Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:1qyw5wSUkEvH8DtaCdx7Lg Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:21IVPfi81m6ywNgOvqTj1i Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:25jgQBxuUkGDdCG1WGKKN9 Dec 15 23:34:09 volumio volumio[1533]: info: Adding Item to queue: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:09 volumio volumio[1533]: info: Using cached record of: spotify:track:4tVVmShMF2PYdLqcejrsy2 Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:09 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:09 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:09 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:09 volumio volumio[1533]: info: [1765809249030] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:09 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:09 volumio go-librespot[1852]: time="2025-12-15T23:34:09+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:10 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:10 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:10 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:10 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:10 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:10 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:10 volumio volumio[1533]: info: [1765809250914] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:10 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:11 volumio go-librespot[1852]: time="2025-12-15T23:34:11+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:11 volumio go-librespot[1852]: time="2025-12-15T23:34:11+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:11 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:11 volumio go-librespot[1852]: time="2025-12-15T23:34:11+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:11 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:13 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:13 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:13 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:13 volumio volumio[1533]: info: [1765809253386] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:13 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:13 volumio go-librespot[1852]: time="2025-12-15T23:34:13+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:13 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:13 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:13 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:13 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:13 volumio volumio[1533]: info: [1765809253803] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:13 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:15 volumio go-librespot[1852]: time="2025-12-15T23:34:15+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:15 volumio go-librespot[1852]: time="2025-12-15T23:34:15+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:15 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:18 volumio go-librespot[1852]: time="2025-12-15T23:34:18+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:18 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:23 volumio go-librespot[1852]: time="2025-12-15T23:34:23+09:00" level=trace msg="sent dealer ping" Dec 15 23:34:23 volumio go-librespot[1852]: time="2025-12-15T23:34:23+09:00" level=trace msg="received dealer pong" Dec 15 23:34:31 volumio sudo[1729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 15 23:34:31 volumio sudo[1729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 23:34:31 volumio sudo[1729]: pam_unix(sudo:session): session closed for user root Dec 15 23:34:31 volumio sudo[1731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 15 23:34:31 volumio sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 23:34:31 volumio sudo[1731]: pam_unix(sudo:session): session closed for user root Dec 15 23:34:31 volumio volumio[1533]: verbose: New Socket.io Connection to 192.168.10.5 from 192.168.10.16 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::volumioGetState Dec 15 23:34:31 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::volumioGetVisibleSources Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 23:34:31 volumio volumio[1533]: info: Listing playlists Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 15 23:34:31 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 23:34:33 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:33 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:33 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:33 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:33 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:33 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:33 volumio volumio[1533]: info: [1765809273486] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:33 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:33 volumio go-librespot[1852]: time="2025-12-15T23:34:33+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:34 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 23:34:34 volumio volumio[1533]: info: Received Get System Info Dec 15 23:34:34 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 23:34:34 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 23:34:34 volumio volumio[1533]: info: Discovery: Getting this device information Dec 15 23:34:34 volumio volumio[1533]: info: CoreCommandRouter::volumioGetState Dec 15 23:34:34 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:34 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 23:34:35 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 23:34:35 volumio volumio[1533]: info: Received Get System Info Dec 15 23:34:35 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 23:34:35 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 23:34:35 volumio volumio[1533]: info: Discovery: Getting this device information Dec 15 23:34:35 volumio volumio[1533]: info: CoreCommandRouter::volumioGetState Dec 15 23:34:35 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:35 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 23:34:36 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:36 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:36 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:36 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:36 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:36 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:36 volumio volumio[1533]: info: [1765809276071] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:36 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:36 volumio go-librespot[1852]: time="2025-12-15T23:34:36+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:36 volumio go-librespot[1852]: time="2025-12-15T23:34:36+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:36 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:37 volumio volumio[1533]: info: CoreCommandRouter::volumioNext Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::next Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:37 volumio volumio[1533]: info: CorePlayQueue::getTrack 1 Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:37 volumio volumio[1533]: info: CorePlayQueue::getTrack 1 Dec 15 23:34:37 volumio volumio[1533]: info: [1765809277089] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:37 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:37 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:37 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:38 volumio go-librespot[1852]: time="2025-12-15T23:34:38+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:38 volumio go-librespot[1852]: time="2025-12-15T23:34:38+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:38 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:39 volumio go-librespot[1852]: time="2025-12-15T23:34:39+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:39 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:41 volumio volumio[1533]: info: CoreCommandRouter::volumioGetQueue Dec 15 23:34:41 volumio volumio[1533]: info: CoreStateMachine::getQueue Dec 15 23:34:41 volumio volumio[1533]: info: CorePlayQueue::getQueue Dec 15 23:34:43 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 15 23:34:43 volumio volumio[1533]: info: In handleBrowseUri, curUri=spotify Dec 15 23:34:43 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:43 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:43 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:43 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:47 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 15 23:34:47 volumio volumio[1533]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:34:47 volumio go-librespot[1852]: time="2025-12-15T23:34:47+09:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXbYM3nMM0oPk" Dec 15 23:34:47 volumio go-librespot[1852]: time="2025-12-15T23:34:47+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 15 23:34:48 volumio go-librespot[1852]: time="2025-12-15T23:34:48+09:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXbYM3nMM0oPk" Dec 15 23:34:48 volumio go-librespot[1852]: time="2025-12-15T23:34:48+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 15 23:34:49 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 15 23:34:49 volumio volumio[1533]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:34:49 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2tHwzyyOLoWSFqYNjeVMzj Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6jjYDGxVJsWS0a5wlVF5vS Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2HRgqmZQC0MC7GeNuDIXHN Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6LsAAHotRLMOHfCsSfYCsz Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4JNdwEfqwFRiAeEISC8RU8 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0mBP9X2gPCuapvpZ7TGDk3 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5QDLhrAOJJdNAmCTJ8xMyW Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5vNRhkKd0yEAg8suGBpjeY Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1rqqCSm0Qe4I9rUvWncaom Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1k2pQc5i348DCHwbn5KTdc Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6tCd8bPvYnceDG7W9M1RMk Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4a9tbd947vo9K8Vti9JwcI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:18uwL0vNUanqZH0ro2QcOP Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1BxfuPKGuaTgP7aM0Bbdwr Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:50nfwKoDiSYg8zOCREWAm5 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6maTPqynTmrkWIralgGaoP Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2wrJq5XKLnmhRXHIAf9xBa Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1x5sYLZiu9r5E43kMlt9f8 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4h9wh7iOZ0GGn8QVp4RAOB Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4Dvkj6JhhA12EX05fT7y2e Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:3FeVmId7tL5YN8B7R3imoM Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0QHEIqNKsMoOY5urbzN48u Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4xdBrk0nFZaP54vvZj0yx7 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7ne4VBA60CxGM75vw0EYad Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5HCyWlXZPP0y6Gqq8TgA20 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0NLm9bQG7ikL5k9x9TtYT7 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:11C4y2Yz1XbHmaQwO06s9f Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6tNQ70jh4OwmPGpYy6R2o9 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7ju97lgwC2rKQ6wwsf9no9 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0WtM2NBVQNNJLh6scP13H8 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2HRqTpkrJO5ggZyyK6NPWz Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0WbMK4wrZ1wFSty9F7FCgu Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0n2nEtLP3OzDdi12ziIekx Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4ZtFanR9U6ndgddUvNcjcG Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6zSpb8dQRaw0M1dK8PBwQz Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2Fxmhks0bxGSBdJ92vM42m Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7a53HqqArd4b9NF4XAmlbI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0HqZX76SFLDz2aW8aiqi7G Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2jpKZFnBk98Ud3EoZiBOTf Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:19XpFsce28aByvCC4g89tJ Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4yNk9iz9WVJikRFle3XEvn Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:22vgEDb5hykfaTwLuskFGD Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1r9xUipOqoNwggBpENDsvJ Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5G2f63n7IPVPPjfNIGih7Q Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:0V3wPSX9ygBnCm8psDIegu Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:45OX2jjEw1l7lOFJfDP9fv Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5dPz35akJjPqb17yeqNwqH Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:19RybK6XDbAVpcdxSbZL1o Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1kuGVB7EU95pJObxwvfwKS Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:45J4avUb9Ni0bnETYaYFVJ Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5mHdCZtVyb4DcJw8799hZp Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6UelLqGlWMcVH1E5c4H7lY Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7DnAm9FOTWE3cUvso43HhI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4cktbXiXOapiLBMprHFErI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2FQrifJ1N335Ljm3TjTVVf Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:3KkXRkHbMCARz0aVfEt68P Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2qxmye6gAegTMjLKEBoR3d Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4ZtFanR9U6ndgddUvNcjcG Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2Fxmhks0bxGSBdJ92vM42m Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5nujrmhLynf4yMoMtj8AQF Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5Z3GHaZ6ec9bsiI5BenrbY Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:02VBYrHfVwfEWXk5DXyf0T Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:3e9HZxeyfWwjeyPAMmWSSQ Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7a53HqqArd4b9NF4XAmlbI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7eJMfftS33KTjuF7lTsMCx Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4h9wh7iOZ0GGn8QVp4RAOB Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4qu63nuBpdn0qHUHuObEj1 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:3rmo8F54jFF8OgYsqTxm5d Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:5hx7w26Zi3zafMgvMTUqF6 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:4iJyoBOLtHqaGxP12qzhQI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:3jjujdWJ72nww5eGnfs2E7 Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:2L9N0zZnd37dwF0clgxMGI Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:1rqqCSm0Qe4I9rUvWncaom Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:6NFyWDv5CjfwuzoCkw47Xf Dec 15 23:34:49 volumio volumio[1533]: info: Preloading song: spotify:track:7221xIgOnuakPdLqT0F3nP Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:2tHwzyyOLoWSFqYNjeVMzj in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2tHwzyyOLoWSFqYNjeVMzj Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:6jjYDGxVJsWS0a5wlVF5vS in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6jjYDGxVJsWS0a5wlVF5vS Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:2HRgqmZQC0MC7GeNuDIXHN in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2HRgqmZQC0MC7GeNuDIXHN Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:6LsAAHotRLMOHfCsSfYCsz in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6LsAAHotRLMOHfCsSfYCsz Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:4JNdwEfqwFRiAeEISC8RU8 in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4JNdwEfqwFRiAeEISC8RU8 Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:0mBP9X2gPCuapvpZ7TGDk3 in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0mBP9X2gPCuapvpZ7TGDk3 Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:5QDLhrAOJJdNAmCTJ8xMyW in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:5QDLhrAOJJdNAmCTJ8xMyW Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6jjYDGxVJsWS0a5wlVF5vS","service":"spop","name":"Butter","artist":"BTS","album":"Proof","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b27317db30ce3f081d6818a8ad49","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tHwzyyOLoWSFqYNjeVMzj","service":"spop","name":"Please Please Please","artist":"Sabrina Carpenter","album":"Short n' Sweet","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273fd8d7a8d96871e791cb1f626","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:5vNRhkKd0yEAg8suGBpjeY in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:5vNRhkKd0yEAg8suGBpjeY Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:1rqqCSm0Qe4I9rUvWncaom in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1rqqCSm0Qe4I9rUvWncaom Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:1k2pQc5i348DCHwbn5KTdc in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1k2pQc5i348DCHwbn5KTdc Dec 15 23:34:49 volumio volumio[1533]: info: Exploding uri spotify:track:6tCd8bPvYnceDG7W9M1RMk in service spop Dec 15 23:34:49 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6tCd8bPvYnceDG7W9M1RMk Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HRgqmZQC0MC7GeNuDIXHN","service":"spop","name":"Seven (feat. Latto) (Explicit Ver.)","artist":"Jung Kook","album":"GOLDEN","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273741fd4807f442af3f7359316","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:4a9tbd947vo9K8Vti9JwcI in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4a9tbd947vo9K8Vti9JwcI Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6LsAAHotRLMOHfCsSfYCsz","service":"spop","name":"If I Can't Have You","artist":"Shawn Mendes","album":"If I Can't Have You","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273625acd26e0e455312667565f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:18uwL0vNUanqZH0ro2QcOP in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:18uwL0vNUanqZH0ro2QcOP Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:1BxfuPKGuaTgP7aM0Bbdwr in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1BxfuPKGuaTgP7aM0Bbdwr Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0mBP9X2gPCuapvpZ7TGDk3","service":"spop","name":"Left and Right (Feat. Jung Kook of BTS)","artist":"Charlie Puth","album":"Left and Right (Feat. Jung Kook of BTS)","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b2731c069c836dc6cd5b34c310fe","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5QDLhrAOJJdNAmCTJ8xMyW","service":"spop","name":"Dynamite","artist":"BTS","album":"BE","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273c07d5d2fdc02ae252fcd07e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:50nfwKoDiSYg8zOCREWAm5 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:50nfwKoDiSYg8zOCREWAm5 Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:6maTPqynTmrkWIralgGaoP in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6maTPqynTmrkWIralgGaoP Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1k2pQc5i348DCHwbn5KTdc","service":"spop","name":"Pink Pony Club","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b27396fa88fb1789be437d5cb4b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","service":"spop","name":"APT.","artist":"ROSÉ","album":"APT.","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27336032cb4acd9df050bc2e197","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:2wrJq5XKLnmhRXHIAf9xBa in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2wrJq5XKLnmhRXHIAf9xBa Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1rqqCSm0Qe4I9rUvWncaom","service":"spop","name":"High Hopes","artist":"Panic! At The Disco","album":"Pray for the Wicked","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5148520a59be191eea16989","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6tCd8bPvYnceDG7W9M1RMk","service":"spop","name":"Shut Down","artist":"BLACKPINK","album":"BORN PINK","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273580ac3ad7dfc81e509171120","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:1x5sYLZiu9r5E43kMlt9f8 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:1x5sYLZiu9r5E43kMlt9f8 Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:4h9wh7iOZ0GGn8QVp4RAOB in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4h9wh7iOZ0GGn8QVp4RAOB Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4a9tbd947vo9K8Vti9JwcI","service":"spop","name":"Boy With Luv (feat. Halsey)","artist":"BTS","album":"MAP OF THE SOUL : PERSONA","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b27318d0ed4f969b376893f9a38f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:18uwL0vNUanqZH0ro2QcOP","service":"spop","name":"comethru","artist":"Jeremy Zucker","album":"summer,","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273cb16227d90152c2a5022bba1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:4Dvkj6JhhA12EX05fT7y2e in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4Dvkj6JhhA12EX05fT7y2e Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:50nfwKoDiSYg8zOCREWAm5","service":"spop","name":"Shivers","artist":"Ed Sheeran","album":"=","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273ef24c3fdbf856340d55cfeb2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4JNdwEfqwFRiAeEISC8RU8","service":"spop","name":"Die For You (with Ariana Grande) - Remix","artist":"The Weeknd","album":"Die For You","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b2738b5a9e7c56f01bec356de9d5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1BxfuPKGuaTgP7aM0Bbdwr","service":"spop","name":"Cruel Summer","artist":"Taylor Swift","album":"Lover","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273e787cffec20aa2a396a61647","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:3FeVmId7tL5YN8B7R3imoM in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:3FeVmId7tL5YN8B7R3imoM Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6maTPqynTmrkWIralgGaoP","service":"spop","name":"If We Ever Broke Up","artist":"Mae Stephens","album":"If We Ever Broke Up","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b2731fc63c898797e3dbf04ad611","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:0QHEIqNKsMoOY5urbzN48u in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0QHEIqNKsMoOY5urbzN48u Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wrJq5XKLnmhRXHIAf9xBa","service":"spop","name":"10,000 Hours (with Justin Bieber)","artist":"Dan + Shay","album":"10,000 Hours (with Justin Bieber)","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b27386953b1cbaa29e477db0b479","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1x5sYLZiu9r5E43kMlt9f8","service":"spop","name":"Symphony (feat. Zara Larsson)","artist":"Clean Bandit","album":"Symphony (feat. Zara Larsson)","type":"song","duration":212,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b1130d050c4cd2295999d85","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:4xdBrk0nFZaP54vvZj0yx7 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4xdBrk0nFZaP54vvZj0yx7 Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Dvkj6JhhA12EX05fT7y2e","service":"spop","name":"As It Was","artist":"Harry Styles","album":"Harry's House","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b27382ce362511fb3d9dda6578ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:7ne4VBA60CxGM75vw0EYad in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:7ne4VBA60CxGM75vw0EYad Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4h9wh7iOZ0GGn8QVp4RAOB","service":"spop","name":"I Ain't Worried","artist":"OneRepublic","album":"I Ain’t Worried (Music From The Motion Picture \"Top Gun: Maverick\")","type":"song","duration":148,"albumart":"https://i.scdn.co/image/ab67616d0000b273ec96e006b8bdfc582610ec13","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:5HCyWlXZPP0y6Gqq8TgA20 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:5HCyWlXZPP0y6Gqq8TgA20 Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0QHEIqNKsMoOY5urbzN48u","service":"spop","name":"Made You Look","artist":"Meghan Trainor","album":"Takin' It Back","type":"song","duration":134,"albumart":"https://i.scdn.co/image/ab67616d0000b2735cd76705ba9e33340a92bca7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:0NLm9bQG7ikL5k9x9TtYT7 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0NLm9bQG7ikL5k9x9TtYT7 Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3FeVmId7tL5YN8B7R3imoM","service":"spop","name":"My Universe","artist":"Coldplay","album":"My Universe","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273f60a9b7e2abafc38da31f575","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:11C4y2Yz1XbHmaQwO06s9f in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:11C4y2Yz1XbHmaQwO06s9f Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 15 23:34:50 volumio volumio[1533]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 15 23:34:50 volumio volumio[1533]: info: Received Get System Version Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 15 23:34:50 volumio volumio[1533]: info: Received Get System Info Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 15 23:34:50 volumio volumio[1533]: info: Discovery: Getting this device information Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::volumioGetState Dec 15 23:34:50 volumio volumio[1533]: info: CorePlayQueue::getTrack 1 Dec 15 23:34:50 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xdBrk0nFZaP54vvZj0yx7","service":"spop","name":"HOT TO GO!","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b27396fa88fb1789be437d5cb4b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:6tNQ70jh4OwmPGpYy6R2o9 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6tNQ70jh4OwmPGpYy6R2o9 Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ne4VBA60CxGM75vw0EYad","service":"spop","name":"That’s So True","artist":"Gracie Abrams","album":"The Secret of Us (Deluxe)","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b2731dac3694b3289cd903cb3acf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:7ju97lgwC2rKQ6wwsf9no9 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:7ju97lgwC2rKQ6wwsf9no9 Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5HCyWlXZPP0y6Gqq8TgA20","service":"spop","name":"STAY (with Justin Bieber)","artist":"The Kid LAROI","album":"STAY (with Justin Bieber)","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b273aed1660585c1e3c9ffb50b6a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:0WtM2NBVQNNJLh6scP13H8 in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0WtM2NBVQNNJLh6scP13H8 Dec 15 23:34:50 volumio volumio[1533]: info: Exploding uri spotify:track:2HRqTpkrJO5ggZyyK6NPWz in service spop Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2HRqTpkrJO5ggZyyK6NPWz Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0NLm9bQG7ikL5k9x9TtYT7","service":"spop","name":"Here With Me","artist":"d4vd","album":"Petals to Thorns","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273e5ff1941799cd30cb2aa072b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:50 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:11C4y2Yz1XbHmaQwO06s9f","service":"spop","name":"Dance The Night","artist":"Dua Lipa","album":"Barbie The Album","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273d16ac0ec27653182d719ab36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:track:0WbMK4wrZ1wFSty9F7FCgu in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0WbMK4wrZ1wFSty9F7FCgu Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:track:0n2nEtLP3OzDdi12ziIekx in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:0n2nEtLP3OzDdi12ziIekx Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ju97lgwC2rKQ6wwsf9no9","service":"spop","name":"Rain On Me (with Ariana Grande)","artist":"Lady Gaga","album":"Chromatica","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b2736040effba89b9b00a6f6743a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:track:4ZtFanR9U6ndgddUvNcjcG in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:4ZtFanR9U6ndgddUvNcjcG Dec 15 23:34:51 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6dOtVTDdiauQNBQEDOtlAB Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2plbrEY59IikOBgBGLjaoe Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:1PtQJZVZIdWIYdARpZRDFO Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:0pqnGHJpmpxLKifKRmU6WP Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:0VjIjW4GlUZAMYd2vXMi3b Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7qEHsqek33rTcFNT9PFqLf Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5G2f63n7IPVPPjfNIGih7Q Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5CZ40GBx1sQ9agT82CLQCT Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4iZ4pt7kvcaH6Yo8UoZ4s2 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4xqrdfXkTW4T0RauPLv3WA Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3WMj8moIAXJhHsyLaqIIHI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:21jGcNKet2qwijlDFuPiPb Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3USxtqRwSYz57Ewm6wWRMp Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5wANPM4fQCJwkGd4rN57mH Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6ocbgoVGwYJhOv1GgI9NsF Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:0mflMxspEfB0VbI1kyLiAv Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6HU7h9RYOaPRFeh0R3UeAr Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4l0RmWt52FxpVxMNni6i63 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5aIVCx5tnk0ntmdiinnYvw Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:1zB4vmk8tFRmM9UULNzbLB Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4R2kfaDFhslZEMJqAFNpdd Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6zSpb8dQRaw0M1dK8PBwQz Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6wf7Yu7cxBSPrRlWeSeK0Q Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7DSAEUvxU8FajXtRloy8M0 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3DarAbFujv6eYNliUTyqtz Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4uUG5RXrOk84mYEfFvj3cK Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:19XpFsce28aByvCC4g89tJ Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2VxeLyX666F8uXCJ0dZF8B Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:1kuGVB7EU95pJObxwvfwKS Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6FZDfxM3a3UCqtzo5pxSLZ Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:1dGr1c8CrMLDpV6mPbImSI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:45J4avUb9Ni0bnETYaYFVJ Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2mlNgAeIBnL78ZriXgrRHz Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2gMXnyrvIjhVBUZwvLZDMP Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6Uj1ctrBOjOas8xZXGqKk4 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5mHdCZtVyb4DcJw8799hZp Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6UelLqGlWMcVH1E5c4H7lY Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7DnAm9FOTWE3cUvso43HhI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4cktbXiXOapiLBMprHFErI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2FQrifJ1N335Ljm3TjTVVf Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3KkXRkHbMCARz0aVfEt68P Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2qxmye6gAegTMjLKEBoR3d Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2Fxmhks0bxGSBdJ92vM42m Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5nujrmhLynf4yMoMtj8AQF Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5Z3GHaZ6ec9bsiI5BenrbY Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:02VBYrHfVwfEWXk5DXyf0T Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3e9HZxeyfWwjeyPAMmWSSQ Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7a53HqqArd4b9NF4XAmlbI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7eJMfftS33KTjuF7lTsMCx Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4qu63nuBpdn0qHUHuObEj1 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3rmo8F54jFF8OgYsqTxm5d Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:5hx7w26Zi3zafMgvMTUqF6 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:4iJyoBOLtHqaGxP12qzhQI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:3jjujdWJ72nww5eGnfs2E7 Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:2L9N0zZnd37dwF0clgxMGI Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:6NFyWDv5CjfwuzoCkw47Xf Dec 15 23:34:51 volumio volumio[1533]: info: Preloading song: spotify:track:7221xIgOnuakPdLqT0F3nP Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6tNQ70jh4OwmPGpYy6R2o9","service":"spop","name":"Beautiful Things","artist":"Benson Boone","album":"Beautiful Things","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273bef221ea02a821e7feeda9cf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WtM2NBVQNNJLh6scP13H8","service":"spop","name":"Calm Down (with Selena Gomez)","artist":"Rema","album":"Calm Down (with Selena Gomez)","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b273a3a7f38ea2033aa501afd4cf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:track:6dOtVTDdiauQNBQEDOtlAB in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:6dOtVTDdiauQNBQEDOtlAB Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2HRqTpkrJO5ggZyyK6NPWz","service":"spop","name":"Espresso","artist":"Sabrina Carpenter","album":"Short n' Sweet","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273fd8d7a8d96871e791cb1f626","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:track:2plbrEY59IikOBgBGLjaoe in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:track:2plbrEY59IikOBgBGLjaoe Dec 15 23:34:51 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:51 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:34:51 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:34:51 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:51 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:51 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:34:51 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:51 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:51 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:34:51 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:34:51 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:34:51 volumio volumio[1533]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:34:51 volumio volumio[1533]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DXbYM3nMM0oPk in service spop Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0n2nEtLP3OzDdi12ziIekx","service":"spop","name":"SOS","artist":"Avicii","album":"SOS (feat. Aloe Blacc)","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273773527dd6b1b7995a309a461","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WbMK4wrZ1wFSty9F7FCgu","service":"spop","name":"Good Luck, Babe!","artist":"Chappell Roan","album":"Good Luck, Babe!","type":"song","duration":218,"albumart":"https://i.scdn.co/image/ab67616d0000b27391b4bc7c88d91a42e0f3a8b7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ZtFanR9U6ndgddUvNcjcG","service":"spop","name":"good 4 u","artist":"Olivia Rodrigo","album":"SOUR","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273a91c10fe9472d9bd89802e5a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dOtVTDdiauQNBQEDOtlAB","service":"spop","name":"BIRDS OF A FEATHER","artist":"Billie Eilish","album":"HIT ME HARD AND SOFT","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b27371d62ea7ea8a5be92d3c1f62","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:51 volumio volumio[1533]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2plbrEY59IikOBgBGLjaoe","service":"spop","name":"Die With A Smile","artist":"Lady Gaga","album":"Die With A Smile","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b27382ea2e9e1858aa012c57cd45","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 15 23:34:52 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:34:52 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:34:52 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:34:52 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:52 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:52 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:52 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:52 volumio volumio[1533]: info: [1765809292185] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:52 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:52 volumio go-librespot[1852]: time="2025-12-15T23:34:52+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:52 volumio go-librespot[1852]: time="2025-12-15T23:34:52+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:52 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:34:53 volumio go-librespot[1852]: time="2025-12-15T23:34:53+09:00" level=trace msg="sent dealer ping" Dec 15 23:34:53 volumio go-librespot[1852]: time="2025-12-15T23:34:53+09:00" level=trace msg="received dealer pong" Dec 15 23:34:57 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:34:57 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:34:57 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:34:57 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:57 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:34:57 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:34:57 volumio volumio[1533]: info: [1765809297813] ControllerSpotify::clearAddPlayTrack Dec 15 23:34:57 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:34:57 volumio go-librespot[1852]: time="2025-12-15T23:34:57+09:00" level=debug msg="renewing login5 access token" Dec 15 23:34:58 volumio go-librespot[1852]: time="2025-12-15T23:34:58+09:00" level=error msg="failed handling request play" error="failed resolving context: spclient request failed: failed obtaining spclient access token: failed renewing login5 access token: failed authenticating with login5: UNKNOWN_ERROR" Dec 15 23:34:58 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 15 23:35:00 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 15 23:35:00 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 15 23:35:03 volumio go-librespot[1852]: time="2025-12-15T23:35:03+09:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Dec 15 23:35:03 volumio volumio[1533]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Dec 15 23:35:03 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 23:35:03 volumio volumio[1533]: Cannot find translation for source Randomizer Dec 15 23:35:03 volumio volumio[1533]: info: Disabling plugin spop Dec 15 23:35:03 volumio volumio[1533]: info: Done. Dec 15 23:35:03 volumio volumio[1533]: info: Connection to go-librespot Websocket closed Dec 15 23:35:03 volumio sudo[1791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Dec 15 23:35:03 volumio sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 23:35:03 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 15 23:35:03 volumio systemd[1]: go-librespot-daemon.service: Killing process 1938 (go-librespot) with signal SIGKILL. Dec 15 23:35:03 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 15 23:35:03 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 23:35:03 volumio systemd[1]: go-librespot-daemon.service: Consumed 13min 22.157s CPU time. Dec 15 23:35:03 volumio 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. Dec 15 23:35:03 volumio 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. Dec 15 23:35:03 volumio 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. Dec 15 23:35:03 volumio 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. Dec 15 23:35:03 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Dec 15 23:35:04 volumio volumio[1533]: info: Enabling plugin spop Dec 15 23:35:04 volumio volumio[1533]: info: Loading plugin "spop"... Dec 15 23:35:04 volumio volumio[1533]: info: PLUGIN START: spop Dec 15 23:35:04 volumio volumio[1533]: info: Creating Spotify config file Dec 15 23:35:04 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 15 23:35:04 volumio volumio[1533]: info: Done. Dec 15 23:35:04 volumio volumio[1533]: info: Spotify config file written Dec 15 23:35:04 volumio volumio[1533]: info: No need to fix Spotify hosts Dec 15 23:35:04 volumio sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 15 23:35:04 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 15 23:35:04 volumio 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. Dec 15 23:35:04 volumio 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. Dec 15 23:35:04 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 23:35:04 volumio go-librespot[1796]: go-librespot daemon starting... Dec 15 23:35:04 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=info msg="running go-librespot 0.4.0" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="app state loaded" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=info msg="zeroconf server listening on port 36101" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="obtained new client token: AACNRYRxIivy/+hjWCStCm0gKpFnTOgtgfiq4sGkaFsuEJqAymsztFjRpsZrY/U33nSNbECnJ/HZ8p+AZZsSC/WhaWXFnQ8CRHGvzZZRE4Iln8b4IbA16dRaD6XsvlejV/c67EuG3UIM91756BYDmrw2cCPxwgLKCVBKbxdUgnJ2MQW6rhwefd5Fo1HtobDA7uPzfwls2KG9CDdgiekw4LXQGOKXxA34au95TC6y4gPDYKNQXqPi844C0Dko" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="completed keyexchange" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=debug msg="completed challenge" Dec 15 23:35:04 volumio go-librespot[1797]: time="2025-12-15T23:35:04+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TryAnotherAP " Dec 15 23:35:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 23:35:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 23:35:05 volumio volumio[1533]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 23:35:05 volumio volumio[1533]: SPOTIFY: BQBD3h662rK8YWByxKa1UbPTKxpxTdCMSCSISP03-0nueagX9DPYG9HR6S8Mq7SWkJKqgR2yNuQKwcNjkGQfD3d7UFNKNhxImpjFSi7BtakqJDY1ib6CHvyODEzpzeNIvbUBsIIdIaczs5XWU46c62ulJeBVPLkiWCBsEVrbyegr4DC4QfDyfl0FldGZVz7j6cqT7pndyFJYZy-PgZhBOwCzSminecXAJc49Sp-SS_3X6iabFe2v8LDJqCGNOzmSBxr1xoAppvgehO6VSTSpxq98quihyAg5x65V1Cz-jSk94DskMiuzNLeF Dec 15 23:35:05 volumio volumio[1533]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 15 23:35:05 volumio volumio[1533]: info: New Spotify access token = BQBD3h662rK8YWByxKa1UbPTKxpxTdCMSCSISP03-0nueagX9DPYG9HR6S8Mq7SWkJKqgR2yNuQKwcNjkGQfD3d7UFNKNhxImpjFSi7BtakqJDY1ib6CHvyODEzpzeNIvbUBsIIdIaczs5XWU46c62ulJeBVPLkiWCBsEVrbyegr4DC4QfDyfl0FldGZVz7j6cqT7pndyFJYZy-PgZhBOwCzSminecXAJc49Sp-SS_3X6iabFe2v8LDJqCGNOzmSBxr1xoAppvgehO6VSTSpxq98quihyAg5x65V1Cz-jSk94DskMiuzNLeF Dec 15 23:35:05 volumio volumio[1533]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 15 23:35:05 volumio volumio[1533]: SPOTIFY: User informations: {"country":"JP","display_name":"シラッキー","email":"ken48933@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/kgd70lugf7i80w9sup498hcei"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/kgd70lugf7i80w9sup498hcei","id":"kgd70lugf7i80w9sup498hcei","images":[],"product":"premium","type":"user","uri":"spotify:user:kgd70lugf7i80w9sup498hcei"} Dec 15 23:35:05 volumio volumio[1533]: info: Spotify Successfully logged in Dec 15 23:35:05 volumio volumio[1533]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 15 23:35:05 volumio volumio[1533]: info: [1765809305557] CoreMusicLibrary::Adding element Spotify Dec 15 23:35:05 volumio volumio[1533]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 15 23:35:05 volumio volumio[1533]: Cannot find translation for source Randomizer Dec 15 23:35:05 volumio volumio[1533]: Cannot find translation for source Spotify Dec 15 23:35:06 volumio volumio[1533]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 15 23:35:07 volumio volumio[1533]: info: go-librespot daemon successfully initialized Dec 15 23:35:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 15 23:35:08 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 15 23:35:08 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 15 23:35:08 volumio go-librespot[1819]: go-librespot daemon starting... Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=info msg="running go-librespot 0.4.0" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="app state loaded" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=info msg="zeroconf server listening on port 43223" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="obtained new client token: AADNOhsg21LVmH+lSfecKklYZqf0zZGLPOwDuN3uhw2MjA7JedUVJh1kM8DbL4MYlPcB3Y62Z/SJ/bnUDzLssO6b4tkUvNjxOPnU1tdj/kHLFBZS8CZ4YDUSYLCTZ75Qv9P7i1lAHFx3bVIvJPf8waz29aD8pLN+SLZy/V2nGlfLvzgxOFGUk9qmQ1d2KuuniI2QIAabYVRwbGpUIJ6lJ++Pf4/xAlGKz90XZHz17KAChfftVQFi76H/a9+/" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="completed keyexchange" Dec 15 23:35:08 volumio go-librespot[1820]: time="2025-12-15T23:35:08+09:00" level=debug msg="completed challenge" Dec 15 23:35:10 volumio volumio[1533]: info: Initializing connection to go-librespot Websocket Dec 15 23:35:10 volumio go-librespot[1820]: time="2025-12-15T23:35:10+09:00" level=debug msg="new websocket client" Dec 15 23:35:10 volumio volumio[1533]: info: Connection to go-librespot Websocket established Dec 15 23:35:11 volumio go-librespot[1820]: time="2025-12-15T23:35:11+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TryAnotherAP " Dec 15 23:35:11 volumio volumio[1533]: info: Connection to go-librespot Websocket closed Dec 15 23:35:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 15 23:35:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 15 23:35:11 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:35:11 volumio volumio[1533]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::ClearQueue Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::clearPlayQueue Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:35:11 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::addQueueItems Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::addQueueItems Dec 15 23:35:11 volumio volumio[1533]: info: Preload queue cleared Dec 15 23:35:11 volumio volumio[1533]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:35:11 volumio volumio[1533]: info: Using cached record of: spotify:user:spotify:playlist:37i9dQZF1DXbYM3nMM0oPk Dec 15 23:35:11 volumio volumio[1533]: info: CoreCommandRouter::volumioPushQueue Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::saveQueue Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::updateTrackBlock Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::getTrackBlock Dec 15 23:35:11 volumio volumio[1533]: info: CoreCommandRouter::volumioPlay Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::play index 0 Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::stop Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::play index undefined Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:35:11 volumio volumio[1533]: info: CoreStateMachine::startPlaybackTimer Dec 15 23:35:11 volumio volumio[1533]: info: CorePlayQueue::getTrack 0 Dec 15 23:35:11 volumio volumio[1533]: info: [1765809311330] ControllerSpotify::clearAddPlayTrack Dec 15 23:35:11 volumio volumio[1533]: info: Sending Spotify command with payload to local API: /player/play Dec 15 23:35:11 volumio volumio[1533]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 23:35:13 volumio volumio[1533]: info: Getting Spotify volume Dec 15 23:35:13 volumio volumio[1533]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 23:35:13 volumio volumio[1533]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 15 23:35:13 volumio volumio[1533]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 15 23:35:13 volumio volumio[1533]: errno: -111, Dec 15 23:35:13 volumio volumio[1533]: code: 'ECONNREFUSED', Dec 15 23:35:13 volumio volumio[1533]: syscall: 'connect', Dec 15 23:35:13 volumio volumio[1533]: address: '127.0.0.1', Dec 15 23:35:13 volumio volumio[1533]: port: 9879, Dec 15 23:35:13 volumio volumio[1533]: response: undefined Dec 15 23:35:13 volumio volumio[1533]: } Dec 15 23:35:13 volumio volumio[1533]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 15 23:35:13 volumio sudo[1843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-15 23:34' Dec 15 23:35:13 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"