-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-02-26 14:38:13 CET. -- Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:0pfTlQJBOV4LUmF8qqrVy5 in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:0pfTlQJBOV4LUmF8qqrVy5 Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:7C5icC5RyiadSULhkvzuaS in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:7C5icC5RyiadSULhkvzuaS Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:0TTrhheLYj4JL2G1mBFa5q in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:0TTrhheLYj4JL2G1mBFa5q Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:58gmiyOSGBNwjf21X6m7Su in service spop Feb 26 14:37:00 volumio go-librespot[1537]: time="2025-02-26T14:37:00+01:00" level=debug msg="fetched first chunk of 28, total size is 14177612 bytes" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:58gmiyOSGBNwjf21X6m7Su Feb 26 14:37:00 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:00 volumio go-librespot[1537]: time="2025-02-26T14:37:00+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed setting stream for spotify:track:4RZ7Lwj1bivN1payIeOKXp: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:00 volumio volumio[1068]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:1wHELVbiYAOjUAaFs5BC2Y in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:1wHELVbiYAOjUAaFs5BC2Y Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:2Vwa9SOgZPLDr8bue6gLyB in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:2Vwa9SOgZPLDr8bue6gLyB Feb 26 14:37:00 volumio go-librespot[1537]: time="2025-02-26T14:37:00+01:00" level=debug msg="fetched chunk 1/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:6zfczP87XO2SxWlQtnjFNa in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:6zfczP87XO2SxWlQtnjFNa Feb 26 14:37:00 volumio go-librespot[1537]: time="2025-02-26T14:37:00+01:00" level=debug msg="fetched chunk 2/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:00 volumio go-librespot[1537]: time="2025-02-26T14:37:00+01:00" level=debug msg="fetched chunk 3/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:2akjLHnHjDGsgqxuf48VYs in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:2akjLHnHjDGsgqxuf48VYs Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:3yEN31fZv3uWUMluwCbYcf in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:3yEN31fZv3uWUMluwCbYcf Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:5G1sTBGbZT5o4PNRc75RKI in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:5G1sTBGbZT5o4PNRc75RKI Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:3Hx7RXqCS7Kzjy2ot2q1Gk in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:3Hx7RXqCS7Kzjy2ot2q1Gk Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:4MoMUQ1qa67DnzqyjbmPAV in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:4MoMUQ1qa67DnzqyjbmPAV Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:2LGdO5MtFdyphi2EihANZG in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:2LGdO5MtFdyphi2EihANZG Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:2x8evxqUlF0eRabbW2JBJd in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:2x8evxqUlF0eRabbW2JBJd Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:2AT8iROs4FQueDv2c8q2KE in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:2AT8iROs4FQueDv2c8q2KE Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:7aOor99o8NNLZYElOXlBG1 in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:7aOor99o8NNLZYElOXlBG1 Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:0MN0Xy6I4i2y03BrTCTU4E in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:0MN0Xy6I4i2y03BrTCTU4E Feb 26 14:37:00 volumio volumio[1068]: info: Exploding uri spotify:track:6JDWyQwpYsTJVUbAg6g6rH in service spop Feb 26 14:37:00 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:track:6JDWyQwpYsTJVUbAg6g6rH Feb 26 14:37:07 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 26 14:37:07 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1PnRm85BRYWzuYOo93xvBV","service":"spop","name":"Will You Be Mine","artist":"Smoove & Turrell","album":"Broken Toys","type":"song","duration":280,"albumart":"https://i.scdn.co/image/ab67616d0000b273887a20c732fdbebbf234dfa6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6EidGY4S6hkU6fwNdSSabg","service":"spop","name":"Oh Sheit it's X","artist":"Thundercat","album":"Apocalypse","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b27375cdf80cc38b918251ffbda1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Vwa9SOgZPLDr8bue6gLyB","service":"spop","name":"Foreign Language (feat. Jess) [Flight Facilities Extended Mix]","artist":"Flight Facilities","album":"Foreign Language (Remixes)","type":"song","duration":359,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d36059e091f469be211f580","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6IPFsG4OpNShJfJ4LoeJW0","service":"spop","name":"Beat Goes On","artist":"The All Seeing I","album":"Pickled Eggs and Sherbet","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273b547adfdbd839add6bcd1f73","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3tkbjyAmQnGsD52Rn8TCX8","service":"spop","name":"Idlewild Blue (Don'tchu Worry 'Bout Me)","artist":"Outkast","album":"Idlewild","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2733a6e47b30d9af72db1dba2da","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2akjLHnHjDGsgqxuf48VYs","service":"spop","name":"The Bay","artist":"Metronomy","album":"The English Riviera","type":"song","duration":290,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ca7d89456553e4bbbf3c981","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zfczP87XO2SxWlQtnjFNa","service":"spop","name":"The Look","artist":"Metronomy","album":"The English Riviera","type":"song","duration":277,"albumart":"https://i.scdn.co/image/ab67616d0000b2738ca7d89456553e4bbbf3c981","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yEN31fZv3uWUMluwCbYcf","service":"spop","name":"Heartbreaker","artist":"Metronomy","album":"Nights Out","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b273e28fa05d81362d4f35c2933d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0R2eB1y0q2e9g0RD1sd2dj","service":"spop","name":"You Can't Hold Me Down","artist":"Us3","album":"Acid Jazz (Jazz Club)","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4ebc0858f1bd44195760eb1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0aaEuIrlp7s4V42WfNQtsg","service":"spop","name":"When We Move [Feat. Black Thought & Seun Kuti]","artist":"Common","album":"When We Move [Feat. Black Thought & Seun Kuti]","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b273f53dee502a3d02150fd00376","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5G1sTBGbZT5o4PNRc75RKI","service":"spop","name":"Lonely Boy","artist":"The Black Keys","album":"El Camino","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b2736a21b97de47168df4f0c1993","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Hx7RXqCS7Kzjy2ot2q1Gk","service":"spop","name":"Fever","artist":"The Black Keys","album":"Turn Blue","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2731af8fb0d8859055d35d2290f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0lWEatZXBBYUzEQX5aMeSj","service":"spop","name":"Like Sugar","artist":"Chaka Khan","album":"Hello Happiness","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b273be09205f91e27097f583f058","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4tu8uSWqCWDmGZsVAF4ey8","service":"spop","name":"Born in a War","artist":"Future Islands","album":"Born in a War","type":"song","duration":253,"albumart":"https://i.scdn.co/image/ab67616d0000b273151a156e992a7e00fb1b0a90","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4MoMUQ1qa67DnzqyjbmPAV","service":"spop","name":"Dead and Gone - Live in Studio","artist":"The Black Keys","album":"Tour Rehearsal Tapes","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b2736969b41c5c5a7f0ecbbb1871","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5zGElc4Fk95ONl7YihpLFN","service":"spop","name":"Chalk Tablet Towers (feat. St Vincent)","artist":"Gorillaz","album":"Song Machine, Season One: Strange Timez (Deluxe)","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b27305a3c11cb2cb7c60d30316a3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LGdO5MtFdyphi2EihANZG","service":"spop","name":"Knee Socks","artist":"Arctic Monkeys","album":"AM","type":"song","duration":257,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ae1c4c5c45aabe565499163","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2x8evxqUlF0eRabbW2JBJd","service":"spop","name":"Fluorescent Adolescent","artist":"Arctic Monkeys","album":"Favourite Worst Nightmare","type":"song","duration":183,"albumart":"https://i.scdn.co/image/ab67616d0000b273b1f8da74f225fa1225cdface","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2AT8iROs4FQueDv2c8q2KE","service":"spop","name":"R U Mine?","artist":"Arctic Monkeys","album":"AM","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ae1c4c5c45aabe565499163","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:29T38cNnwgrAid115xyQT9","service":"spop","name":"Funky Nassau","artist":"Orgone","album":"The Killion Floor","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b273ca8e7822f2161fda17de6832","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Q4KScfKgBfmDTFGa2yJsK","service":"spop","name":"Žena od vanilije","artist":"Svemirko","album":"Vanilija","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b273f86874ed2e9f4e31d2531c43","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5CMZV18nTDRypiEz9KWeoD","service":"spop","name":"Miris grada","artist":"Svemirko","album":"Vanilija","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b273f86874ed2e9f4e31d2531c43","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7aOor99o8NNLZYElOXlBG1","service":"spop","name":"Highway Tune","artist":"Greta Van Fleet","album":"From The Fires","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b27357b7f789d328c205b4d15893","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:09 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:18zCdWd3JqoNKboAawlQCY","service":"spop","name":"Mind Control - Radio Edit","artist":"FRIENDS","album":"Mind Control (Radio Edit)","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b2739582ef3768f52e93786f58df","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0MN0Xy6I4i2y03BrTCTU4E","service":"spop","name":"Go Disco, Go","artist":"Koala Voice","album":"Kangaroo's a Neighbour","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b27382f3c3a2c35a846d1b5644e0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6JDWyQwpYsTJVUbAg6g6rH","service":"spop","name":"Don't Try","artist":"Zebra Dots","album":"Walking On A Chance","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff269d0932e45e45cff85097","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4j4mefA9dl4aWRDWDlALuS","service":"spop","name":"Lolo","artist":"Rocket Juice & The Moon","album":"Rocket Juice & The Moon","type":"song","duration":303,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d57a29c14d2787a2a116c80","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1tH0Xwni0oJe4DI6O7naRW","service":"spop","name":"Say That","artist":"Toro y Moi","album":"Anything In Return","type":"song","duration":284,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb423719d651817de5c08ba9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0jDKvy0vxL7DBueBFt3NZ4","service":"spop","name":"Foolin'","artist":"Devendra Banhart","album":"What Will We Be","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273e07d465c85c2c21456366728","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0pfTlQJBOV4LUmF8qqrVy5","service":"spop","name":"Deadly Valentine","artist":"Charlotte Gainsbourg","album":"Rest","type":"song","duration":364,"albumart":"https://i.scdn.co/image/ab67616d0000b273707b41502b707f5f9461c2ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7C5icC5RyiadSULhkvzuaS","service":"spop","name":"Les Oxalis","artist":"Charlotte Gainsbourg","album":"Rest","type":"song","duration":477,"albumart":"https://i.scdn.co/image/ab67616d0000b273707b41502b707f5f9461c2ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6VPXCIfxuOXQoSFJSSKKW4","service":"spop","name":"Dust","artist":"Recloose","album":"Bridging The Pacific, Vol. 1","type":"song","duration":427,"albumart":"https://i.scdn.co/image/ab67616d0000b27358b45c4db878a7d643145d3e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5VxHtln8GvqtvkxMB0tV0S","service":"spop","name":"The Inside Man - Soopasoul remix","artist":"Funky Destination","album":"Revolution Is Only Solution","type":"song","duration":440,"albumart":"https://i.scdn.co/image/ab67616d0000b2735430d69fdb1bb8f076d585f0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0TTrhheLYj4JL2G1mBFa5q","service":"spop","name":"Lemonade","artist":"Fly Moon Royalty","album":"Lemonade","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b2739d5c2f55663b8c434241e02d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:58gmiyOSGBNwjf21X6m7Su","service":"spop","name":"Piece of Me","artist":"Fly Moon Royalty","album":"Unfinished Business","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3e6defd8c6f2581c7ea00cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1wHELVbiYAOjUAaFs5BC2Y","service":"spop","name":"Crave You (feat. Giselle) [Hush Hush Bootleg]","artist":"Flight Facilities","album":"Crave You (feat. Giselle) [Hush Hush Bootleg]","type":"song","duration":341,"albumart":"https://i.scdn.co/image/ab67616d0000b273d19da74b8a2babf8e3a16105","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2cKr1c8FH1yRTmpDRx2Lh0","service":"spop","name":"Slow Down","artist":"Smoove & Turrell","album":"Eccentric Audio","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b27384bfdaebd89d710c7624cd43","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0p2MmOQUAZDnP34obEIYkB","service":"spop","name":"Handkerchief","artist":"KonKoma","album":"KonKoma (Soundway Records)","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2731ef0657cc1759627d455f1ed","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3KbxvGpkQnfFmou6AnNsvb","service":"spop","name":"Voodoo Island Remix - Romanowski vs. Dusty Remix","artist":"Romanowski","album":"The Return of Steady Rocking Romanowski & Friends","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b27314e330a09c8421371e6ec261","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0PlqhYIJxTZr9T5hD2UMe7","service":"spop","name":"Music Is Ruling My World (feat. Karolina) - James Fox Remix Edit","artist":"Kutiman","album":"Music Is Ruling My World","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b27376f5eb1603eb6e8306555578","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3GPbJQjZZ7Zm44JHLQQj3C","service":"spop","name":"It's Dancing Time - Mr Scruff Vs Quantic","artist":"Mr. Scruff","album":"Sweetsmoke","type":"song","duration":398,"albumart":"https://i.scdn.co/image/ab67616d0000b2732c7ec7bf44bee7f105be6064","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:10 volumio volumio[1068]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24dVKnw5U76vYeCRiDGIXG","service":"spop","name":"The Journey / Ghana 74 - Dusty Remix","artist":"The Boogoos","album":"Jazz & Milk Breaks Vol. 2","type":"song","duration":383,"albumart":"https://i.scdn.co/image/ab67616d0000b273bb21d0942e3d23c95860caf8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 26 14:37:12 volumio go-librespot[1537]: time="2025-02-26T14:37:12+01:00" level=debug msg="handling skip_next player command from 5f5d578f5927caa149ca96ade93d02088d6e9c40" Feb 26 14:37:12 volumio go-librespot[1537]: time="2025-02-26T14:37:12+01:00" level=debug msg="resolving autoplay station for 1 tracks" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="resolved autoplay station: spotify:station:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="resolved context of track" uri="spotify:station:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0IONcRDSmK7Bzow0mjTcLA","play_origin":"go-librespot"}} Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0IONcRDSmK7Bzow0mjTcLA","play_origin":"go-librespot"}} Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="selected format OGG_VORBIS_320 (e4218ffdd42c717a73101a242895dda6ad31c865)" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="requested aes key for file e4218ffdd42c717a73101a242895dda6ad31c865, gid: 0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="fetched first chunk of 17, total size is 8856632 bytes" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=warning msg="failed loading station for spotify:station:track:4RZ7Lwj1bivN1payIeOKXp" error="failed loading current track (load context): failed setting stream for spotify:track:0IONcRDSmK7Bzow0mjTcLA: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=trace msg="emitting websocket event: stopped" Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="sending successful reply for dealer request" Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: PUSH STATE SPOTIFY Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 26 14:37:13 volumio volumio[1068]: verbose: CURRENT POSITION 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::syncState stateService stop Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::syncState currentStatus stop Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: info: No code Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":"go-librespot"}} Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: PUSH STATE SPOTIFY Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::servicePushState Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 26 14:37:13 volumio volumio[1068]: verbose: CURRENT POSITION 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::syncState stateService stop Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::syncState currentStatus stop Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: info: No code Feb 26 14:37:13 volumio volumio[1068]: info: CoreStateMachine::pushState Feb 26 14:37:13 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::volumioPushState Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 14:37:13 volumio volumio[1068]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:13 volumio volumio[1068]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:13 volumio go-librespot[1537]: time="2025-02-26T14:37:13+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:0IONcRDSmK7Bzow0mjTcLA" Feb 26 14:37:16 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 26 14:37:16 volumio volumio[1068]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:17 volumio go-librespot[1537]: time="2025-02-26T14:37:17+01:00" level=trace msg="received accesspoint ping" Feb 26 14:37:17 volumio go-librespot[1537]: time="2025-02-26T14:37:17+01:00" level=trace msg="received accesspoint pong ack" Feb 26 14:37:17 volumio go-librespot[1537]: time="2025-02-26T14:37:17+01:00" level=trace msg="sent dealer ping" Feb 26 14:37:17 volumio go-librespot[1537]: time="2025-02-26T14:37:17+01:00" level=trace msg="received dealer pong" Feb 26 14:37:18 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 26 14:37:18 volumio volumio[1068]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:18 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 26 14:37:18 volumio volumio[1068]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:22 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:24 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:26 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:26 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:29 volumio go-librespot[1537]: time="2025-02-26T14:37:29+01:00" level=debug msg="handling skip_next player command from 5f5d578f5927caa149ca96ade93d02088d6e9c40" Feb 26 14:37:29 volumio go-librespot[1537]: time="2025-02-26T14:37:29+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:29 volumio go-librespot[1537]: time="2025-02-26T14:37:29+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 14:37:29 volumio go-librespot[1537]: time="2025-02-26T14:37:29+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 14:37:29 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:27gxLC1bsrp66tDlNVzpHt","play_origin":"go-librespot"}} Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="selected format OGG_VORBIS_320 (1f69c7443cd3ce14985efcaa0a3c67fd1f0dbdf6)" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="requested aes key for file 1f69c7443cd3ce14985efcaa0a3c67fd1f0dbdf6, gid: 27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="fetched first chunk of 19, total size is 9589500 bytes" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=trace msg="seek to 1ms (diff: -199ms, samples: 44, bytes: 12615)" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=warning msg="failed handling dealer request" error="failed skipping to next track: failed loading current track (advance to spotify:track:27gxLC1bsrp66tDlNVzpHt): failed setting stream for spotify:track:27gxLC1bsrp66tDlNVzpHt: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:30 volumio go-librespot[1537]: time="2025-02-26T14:37:30+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:27gxLC1bsrp66tDlNVzpHt" Feb 26 14:37:34 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:34 volumio volumio[1068]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 14:37:34 volumio volumio[1068]: info: CoreStateMachine::ClearQueue Feb 26 14:37:34 volumio volumio[1068]: info: CoreStateMachine::stop Feb 26 14:37:34 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:37:34 volumio volumio[1068]: info: CorePlayQueue::clearPlayQueue Feb 26 14:37:34 volumio volumio[1068]: info: CorePlayQueue::saveQueue Feb 26 14:37:34 volumio volumio[1068]: info: CoreCommandRouter::volumioPushQueue Feb 26 14:37:34 volumio volumio[1068]: info: CoreStateMachine::addQueueItems Feb 26 14:37:34 volumio volumio[1068]: info: CorePlayQueue::addQueueItems Feb 26 14:37:34 volumio volumio[1068]: info: Preload queue cleared Feb 26 14:37:34 volumio volumio[1068]: info: Adding Item to queue: spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:34 volumio volumio[1068]: info: Exploding uri spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh in service spop Feb 26 14:37:34 volumio volumio[1068]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:2JbZu6QQRKD8dfJi3CtjRh Feb 26 14:37:39 volumio go-librespot[1537]: time="2025-02-26T14:37:39+01:00" level=debug msg="handling play player command from 5f5d578f5927caa149ca96ade93d02088d6e9c40" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:6MT9cQVmyEZYb7O74nPi1L" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=trace msg="fetched new page 0 with 73 items (list: 73)" uri="spotify:playlist:6MT9cQVmyEZYb7O74nPi1L" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 14:37:40 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3il9P7OQ9VJKoo3w1hpDoy","play_origin":"playlist"}} Feb 26 14:37:40 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3il9P7OQ9VJKoo3w1hpDoy","play_origin":"playlist"}} Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=warning msg="original track has no formats, alternatives have a total of 8" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="selected format OGG_VORBIS_320 (86f2d4af046d4c04abc49730e1c2a62192088b6c)" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="requested aes key for file 86f2d4af046d4c04abc49730e1c2a62192088b6c, gid: 3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="fetched first chunk of 28, total size is 14623422 bytes" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=warning msg="failed handling dealer request" error="failed loading current track (load context): failed setting stream for spotify:track:3il9P7OQ9VJKoo3w1hpDoy: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="fetched chunk 2/27, size: 524288" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="fetched chunk 3/27, size: 524288" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:40 volumio go-librespot[1537]: time="2025-02-26T14:37:40+01:00" level=debug msg="fetched chunk 1/27, size: 524288" uri="spotify:track:3il9P7OQ9VJKoo3w1hpDoy" Feb 26 14:37:42 volumio volumio[1068]: info: CoreCommandRouter::volumioPushQueue Feb 26 14:37:42 volumio volumio[1068]: info: CorePlayQueue::saveQueue Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::updateTrackBlock Feb 26 14:37:42 volumio volumio[1068]: info: CorePlayQueue::getTrackBlock Feb 26 14:37:42 volumio volumio[1068]: info: CoreCommandRouter::volumioPlay Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::play index 0 Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::stop Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::play index undefined Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:37:42 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:42 volumio volumio[1068]: info: CoreStateMachine::startPlaybackTimer Feb 26 14:37:42 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:42 volumio volumio[1068]: info: [1740577062113] ControllerSpotify::clearAddPlayTrack Feb 26 14:37:42 volumio volumio[1068]: info: Sending Spotify command with payload to local API: /player/play Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="resolved context of track" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 14:37:42 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4RZ7Lwj1bivN1payIeOKXp","play_origin":"go-librespot"}} Feb 26 14:37:42 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4RZ7Lwj1bivN1payIeOKXp","play_origin":"go-librespot"}} Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="selected format OGG_VORBIS_320 (beb2215d304ee7fb45934e0a0a653d00b4279a7f)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="requested aes key for file beb2215d304ee7fb45934e0a0a653d00b4279a7f, gid: 4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="fetched first chunk of 28, total size is 14177612 bytes" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed setting stream for spotify:track:4RZ7Lwj1bivN1payIeOKXp: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:42 volumio volumio[1068]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="fetched chunk 2/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="fetched chunk 1/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:42 volumio go-librespot[1537]: time="2025-02-26T14:37:42+01:00" level=debug msg="fetched chunk 3/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:46 volumio volumio[1068]: info: CoreCommandRouter::volumioGetState Feb 26 14:37:46 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:47 volumio go-librespot[1537]: time="2025-02-26T14:37:47+01:00" level=trace msg="sent dealer ping" Feb 26 14:37:47 volumio go-librespot[1537]: time="2025-02-26T14:37:47+01:00" level=trace msg="received dealer pong" Feb 26 14:37:49 volumio volumio[1068]: info: CoreCommandRouter::volumioPlay Feb 26 14:37:49 volumio volumio[1068]: info: CoreStateMachine::play index undefined Feb 26 14:37:49 volumio volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:37:49 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:49 volumio volumio[1068]: info: CoreStateMachine::startPlaybackTimer Feb 26 14:37:49 volumio volumio[1068]: info: CorePlayQueue::getTrack 0 Feb 26 14:37:49 volumio volumio[1068]: info: [1740577069357] ControllerSpotify::clearAddPlayTrack Feb 26 14:37:49 volumio volumio[1068]: info: Sending Spotify command with payload to local API: /player/play Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="resolved context of track" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=trace msg="emitting websocket event: will_play" Feb 26 14:37:49 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4RZ7Lwj1bivN1payIeOKXp","play_origin":"go-librespot"}} Feb 26 14:37:49 volumio volumio[1068]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:4RZ7Lwj1bivN1payIeOKXp","play_origin":"go-librespot"}} Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="selected format OGG_VORBIS_320 (beb2215d304ee7fb45934e0a0a653d00b4279a7f)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="requested aes key for file beb2215d304ee7fb45934e0a0a653d00b4279a7f, gid: 4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="fetched first chunk of 28, total size is 14177612 bytes" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed setting stream for spotify:track:4RZ7Lwj1bivN1payIeOKXp: ALSA error at snd_pcm_open: No such device" Feb 26 14:37:49 volumio volumio[1068]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="fetched chunk 2/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="fetched chunk 1/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:37:49 volumio go-librespot[1537]: time="2025-02-26T14:37:49+01:00" level=debug msg="fetched chunk 3/27, size: 524288" uri="spotify:track:4RZ7Lwj1bivN1payIeOKXp" Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: info: Retrieving Cloud Streaming UI Feb 26 14:38:01 volumio volumio[1068]: info: Getting Tidal Cloud Configuration Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: info: Getting Qobuz Cloud Configuration Feb 26 14:38:01 volumio volumio[1068]: info: Asking plugin for UI Config Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: info: Getting Spotify Cloud Configuration Feb 26 14:38:01 volumio volumio[1068]: info: Asking plugin for UI Config Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: info: Saving Spotify Acccount Feb 26 14:38:01 volumio volumio[1068]: info: Got Tidal Cloud Configuration Feb 26 14:38:01 volumio volumio[1068]: info: Got it Feb 26 14:38:01 volumio volumio[1068]: info: Got it Feb 26 14:38:01 volumio volumio[1068]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 14:38:01 volumio volumio[1068]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 14:38:01 volumio volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Feb 26 14:38:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 14:38:02 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 26 14:38:06 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 14:38:10 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 26 14:38:13 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 26 14:38:13 volumio volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 26 14:38:13 volumio volumio[1068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 14:38:13 volumio volumio[1068]: Error: getaddrinfo -3007 Feb 26 14:38:13 volumio volumio[1068]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 26 14:38:13 volumio volumio[1068]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 26 14:38:13 volumio volumio[1068]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 26 14:38:13 volumio volumio[1068]: code: -3007, Feb 26 14:38:13 volumio volumio[1068]: errno: -3007, Feb 26 14:38:13 volumio volumio[1068]: syscall: 'getaddrinfo' Feb 26 14:38:13 volumio volumio[1068]: } Feb 26 14:38:13 volumio volumio[1068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 14:38:13 volumio sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-26 14:37 Feb 26 14:38:13 volumio sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"