-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2025-05-31 16:35:10 CEST. -- May 31 16:34:01 raspamp go-librespot[1213]: time="2025-05-31T16:34:01+02:00" level=trace msg="sent dealer ping" May 31 16:34:01 raspamp go-librespot[1213]: time="2025-05-31T16:34:01+02:00" level=trace msg="received dealer pong" May 31 16:34:02 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 31 16:34:02 raspamp volumio[984]: info: In handleBrowseUri, curUri=spotify May 31 16:34:02 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:02 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:02 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:02 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:04 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 31 16:34:04 raspamp volumio[984]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DWVuV87wUBNwc May 31 16:34:05 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0GRc3eGTg8HBdWLRGYgqIc May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:5lXLzaZoj4j4hfCYO687bN May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6qqrTXSdwiJaq8SO0X2lSe May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1musbempyJAw5gfSKZHXP9 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0TYTQ9vAJM0q4qT1mzDeFq May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:5ITV0zqzjOYfFWpW0xBmRa May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3SKH53SPQbEnZR4cJPVaz2 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0fltq9Zz9JUWU3NMqRXFY0 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6xZCDUaY4XzlhCSHKSW7rf May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6PcVlezWXunO2Bh78iEq0W May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1wrVWI9VExFCUFl8c4cWKI May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:726SEjbF3ToEbYbn43gnxl May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6JFw2mbcNfoFXnrk6AxGK6 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:5ZLUm9eab8y3tqQ1OhQSHI May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:7dzuYJwwtNnfcptmbD58aJ May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:7p9nV83OAXMpeAXx9SXW5i May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1iJ9cSEWYQsd2M7ROFyIZx May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:7wLTLminmuEDTdLbCapAMV May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6xOiC7SgWCFsipcM69jIDO May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3BntzKsnLf2pc0dsvE3Zav May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0wz1wXKDBtlGhfRNpnLtc1 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3sK8wGT43QFpWrvNQsrQya May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1UQtL1gwHWntLwWowiXB4C May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1k2pQc5i348DCHwbn5KTdc May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6zBRSNNizxsGWRQFg2eToG May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6WatFBLVB0x077xWeoVc2k May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3PAkE2potAfVGgTocmrF89 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:72RjC359lcwhf1cON9ppls May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0hQqfUxxTVwYW172Ble1oD May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:14cxJ5uLXafpMWPAsJgEYt May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:79KzEOgXmAIG6yez6MxKdQ May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:2z9FUHRkbkV4BObNzbHsFU May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1aLHvqPB5CxGYzhJB7Hzo1 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:1aGXmfXBq4koas7v3277P3 May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:61U7ZDXC4f5iKatYBwSegE May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:6xX6zmaXeKzB4u9TiM1XgU May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0qlYaRFGvgR6afy1f4BBwD May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3AWDeHLc88XogCaCnZQLVI May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:2UC24t5kdAucpeFtVONiYZ May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3x3K1RP3Zfi2qeMR8kyrNO May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3vE9tuRoHiyNPG5HJQLgNd May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:5SxahezRlC0saXbCALfB7c May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:7s3KNhg90zZhRewUTie2AS May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:08VceLSSJJ83Yz3NTCyxZC May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:32cwemsMJdzzsOYjVzfYMS May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:0hh94JJOOvvgugZUWWQxcr May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:4TRZpw82B3eOXKTmh5NiED May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:7ne4VBA60CxGM75vw0EYad May 31 16:34:05 raspamp volumio[984]: info: Preloading song: spotify:track:3bTdmYAMKIvDLhxYDy9xHU May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:0GRc3eGTg8HBdWLRGYgqIc in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0GRc3eGTg8HBdWLRGYgqIc May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:5lXLzaZoj4j4hfCYO687bN in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:5lXLzaZoj4j4hfCYO687bN May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:6qqrTXSdwiJaq8SO0X2lSe in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6qqrTXSdwiJaq8SO0X2lSe May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:1musbempyJAw5gfSKZHXP9 in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1musbempyJAw5gfSKZHXP9 May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","service":"spop","name":"Azizam","artist":"Ed Sheeran","album":"Azizam","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b273d29a003b6e8d4c1c47bd4da4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:4TwEdnSiTPDR1vg1QZ5K8W in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:4TwEdnSiTPDR1vg1QZ5K8W May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:0TYTQ9vAJM0q4qT1mzDeFq in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0TYTQ9vAJM0q4qT1mzDeFq May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","service":"spop","name":"NINAO","artist":"GIMS","album":"NINAO","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273ddfe430ec4417e80852f6142","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qqrTXSdwiJaq8SO0X2lSe","service":"spop","name":"Ordinary","artist":"Alex Warren","album":"Ordinary","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa2bcf3df669bde08b2e7d36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:5ITV0zqzjOYfFWpW0xBmRa in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:5ITV0zqzjOYfFWpW0xBmRa May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:3SKH53SPQbEnZR4cJPVaz2 in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3SKH53SPQbEnZR4cJPVaz2 May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1musbempyJAw5gfSKZHXP9","service":"spop","name":"Anxiety","artist":"Doechii","album":"Anxiety","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2733de7e97b3e5aa3eaccfc19ec","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:0fltq9Zz9JUWU3NMqRXFY0 in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0fltq9Zz9JUWU3NMqRXFY0 May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0TYTQ9vAJM0q4qT1mzDeFq","service":"spop","name":"Melrose Place","artist":"KeBlack","album":"Melrose Place","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b27322c502b1b253b4c4771fbaad","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:6xZCDUaY4XzlhCSHKSW7rf in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6xZCDUaY4XzlhCSHKSW7rf May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4TwEdnSiTPDR1vg1QZ5K8W","service":"spop","name":"Beautiful People","artist":"David Guetta","album":"Beautiful People","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b2737091ac4e6518b4c612831312","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:6PcVlezWXunO2Bh78iEq0W in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6PcVlezWXunO2Bh78iEq0W May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ITV0zqzjOYfFWpW0xBmRa","service":"spop","name":"Show Me Love","artist":"WizTheMc","album":"Show Me Love","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b2739263dc4504ccf1b02899d9ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SKH53SPQbEnZR4cJPVaz2","service":"spop","name":"Messy","artist":"Lola Young","album":"Messy","type":"song","duration":284,"albumart":"https://i.scdn.co/image/ab67616d0000b273a0cb974834e04f46b63b99a8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:1wrVWI9VExFCUFl8c4cWKI in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1wrVWI9VExFCUFl8c4cWKI May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0fltq9Zz9JUWU3NMqRXFY0","service":"spop","name":"Me Enamore","artist":"RIDSA","album":"Me Enamore","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273865d66ca6b9a00b22e61e75d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:726SEjbF3ToEbYbn43gnxl in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:726SEjbF3ToEbYbn43gnxl May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xZCDUaY4XzlhCSHKSW7rf","service":"spop","name":"Next Summer","artist":"Damiano David","album":"Next Summer","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b27327b99ccbfee751f2a1ed925c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:6JFw2mbcNfoFXnrk6AxGK6 in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6JFw2mbcNfoFXnrk6AxGK6 May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6PcVlezWXunO2Bh78iEq0W","service":"spop","name":"Feel Good","artist":"Charlotte Cardin","album":"Feel Good","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273422d93f6da874ae67ab4aa61","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:5ZLUm9eab8y3tqQ1OhQSHI in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:5ZLUm9eab8y3tqQ1OhQSHI May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:7dzuYJwwtNnfcptmbD58aJ in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:7dzuYJwwtNnfcptmbD58aJ May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:726SEjbF3ToEbYbn43gnxl","service":"spop","name":"Nice To Meet You","artist":"Myles Smith","album":"A Minute...","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b2739ddcb072adff08acd2501a06","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1wrVWI9VExFCUFl8c4cWKI","service":"spop","name":"Break Your Heart","artist":"Bormin’","album":"Break Your Heart","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27399011b584cbe86c0ce3d412d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:7p9nV83OAXMpeAXx9SXW5i in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:7p9nV83OAXMpeAXx9SXW5i May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:1iJ9cSEWYQsd2M7ROFyIZx in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1iJ9cSEWYQsd2M7ROFyIZx May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6JFw2mbcNfoFXnrk6AxGK6","service":"spop","name":"Mood","artist":"KeBlack","album":"Mood","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b2733c67719dd948da57b88faccd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:7wLTLminmuEDTdLbCapAMV in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:7wLTLminmuEDTdLbCapAMV May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ZLUm9eab8y3tqQ1OhQSHI","service":"spop","name":"Abracadabra","artist":"Lady Gaga","album":"Abracadabra","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b27325304f6dc9dea023ce4b985c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7dzuYJwwtNnfcptmbD58aJ","service":"spop","name":"4 Kampé","artist":"Joé Dwèt Filé","album":"4 Kampé","type":"song","duration":214,"albumart":"https://i.scdn.co/image/ab67616d0000b273581167470f79d505f0a60f49","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:06 raspamp volumio[984]: info: Exploding uri spotify:track:6xOiC7SgWCFsipcM69jIDO in service spop May 31 16:34:06 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6xOiC7SgWCFsipcM69jIDO May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7p9nV83OAXMpeAXx9SXW5i","service":"spop","name":"Menina de Vermelho","artist":"MC Menor JP","album":"Menina de Vermelho","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff9efa742106d47f8136d55e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:3BntzKsnLf2pc0dsvE3Zav in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3BntzKsnLf2pc0dsvE3Zav May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1iJ9cSEWYQsd2M7ROFyIZx","service":"spop","name":"maman","artist":"Louane","album":"maman","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b27365032756707d155ec08f9f9b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:0wz1wXKDBtlGhfRNpnLtc1 in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0wz1wXKDBtlGhfRNpnLtc1 May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:3sK8wGT43QFpWrvNQsrQya in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3sK8wGT43QFpWrvNQsrQya May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7wLTLminmuEDTdLbCapAMV","service":"spop","name":"Summer Jam","artist":"Sandro Silva","album":"Summer Jam","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273b763c536a03d9e951048da64","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xOiC7SgWCFsipcM69jIDO","service":"spop","name":"Piano","artist":"Werenoi","album":"Piano","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b2737c02e9af90fa282fa149cf99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:1UQtL1gwHWntLwWowiXB4C in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1UQtL1gwHWntLwWowiXB4C May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3BntzKsnLf2pc0dsvE3Zav","service":"spop","name":"Bad Dreams","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b27370b88fc5a2e13bc5440d947c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:1k2pQc5i348DCHwbn5KTdc in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1k2pQc5i348DCHwbn5KTdc May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0wz1wXKDBtlGhfRNpnLtc1","service":"spop","name":"À tes côtés","artist":"Jungeli","album":"À tes côtés","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273e9125e59f5221165c739fe45","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:6zBRSNNizxsGWRQFg2eToG in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6zBRSNNizxsGWRQFg2eToG May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:6WatFBLVB0x077xWeoVc2k in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6WatFBLVB0x077xWeoVc2k May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3sK8wGT43QFpWrvNQsrQya","service":"spop","name":"DtMF","artist":"Bad Bunny","album":"DeBÍ TiRAR MáS FOToS","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273bbd45c8d36e0e045ef640411","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:3PAkE2potAfVGgTocmrF89 in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3PAkE2potAfVGgTocmrF89 May 31 16:34:07 raspamp volumio[984]: 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"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:72RjC359lcwhf1cON9ppls in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:72RjC359lcwhf1cON9ppls May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1UQtL1gwHWntLwWowiXB4C","service":"spop","name":"Un dimanche avec toi","artist":"Vitaa","album":"Charlotte","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273788ad1cb2a955a4e4a974285","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zBRSNNizxsGWRQFg2eToG","service":"spop","name":"Chambre 04","artist":"DJ Kawest","album":"Chambre 04","type":"song","duration":129,"albumart":"https://i.scdn.co/image/ab67616d0000b27338feffe31f053af44a0c6c7a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:0hQqfUxxTVwYW172Ble1oD in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0hQqfUxxTVwYW172Ble1oD May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6WatFBLVB0x077xWeoVc2k","service":"spop","name":"Si Antes Te Hubiera Conocido","artist":"KAROL G","album":"Si Antes Te Hubiera Conocido","type":"song","duration":195,"albumart":"https://i.scdn.co/image/ab67616d0000b273491678beaffcefac517a699e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:14cxJ5uLXafpMWPAsJgEYt in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:14cxJ5uLXafpMWPAsJgEYt May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3PAkE2potAfVGgTocmrF89","service":"spop","name":"Balade","artist":"1D1R","album":"Balade","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273da37985a7dc6d37b9feec1e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:79KzEOgXmAIG6yez6MxKdQ in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:79KzEOgXmAIG6yez6MxKdQ May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:2z9FUHRkbkV4BObNzbHsFU in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:2z9FUHRkbkV4BObNzbHsFU May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:72RjC359lcwhf1cON9ppls","service":"spop","name":"Tamo Bien","artist":"Enrique Iglesias","album":"Tamo Bien","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e25dc727b5fda6731ea49d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0hQqfUxxTVwYW172Ble1oD","service":"spop","name":"TOUCHÉ","artist":"GIMS","album":"TOUCHÉ","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273abd363943817717eb17377dc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:1aLHvqPB5CxGYzhJB7Hzo1 in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1aLHvqPB5CxGYzhJB7Hzo1 May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:14cxJ5uLXafpMWPAsJgEYt","service":"spop","name":"Everytime We Touch","artist":"Upsilone","album":"Everytime We Touch","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b2737c617ad7081d2f5bfb1157a6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:1aGXmfXBq4koas7v3277P3 in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:1aGXmfXBq4koas7v3277P3 May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79KzEOgXmAIG6yez6MxKdQ","service":"spop","name":"M'envoler","artist":"Jeck","album":"M'envoler","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273bd954cd02530e168c33120c7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:61U7ZDXC4f5iKatYBwSegE in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:61U7ZDXC4f5iKatYBwSegE May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2z9FUHRkbkV4BObNzbHsFU","service":"spop","name":"Born With a Broken Heart","artist":"Damiano David","album":"Born With a Broken Heart","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b273c105fb55474f224476c23008","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:6xX6zmaXeKzB4u9TiM1XgU in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:6xX6zmaXeKzB4u9TiM1XgU May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1aLHvqPB5CxGYzhJB7Hzo1","service":"spop","name":"les filles, les meufs","artist":"marguerite","album":"les filles, les meufs","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b273dfb9b805cdc82e113ce3c654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:0qlYaRFGvgR6afy1f4BBwD in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0qlYaRFGvgR6afy1f4BBwD May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:3AWDeHLc88XogCaCnZQLVI in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3AWDeHLc88XogCaCnZQLVI May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:61U7ZDXC4f5iKatYBwSegE","service":"spop","name":"Simba","artist":"Dr. Yaro","album":"Simba","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b273b34efbb51bd148d3c461d280","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1aGXmfXBq4koas7v3277P3","service":"spop","name":"I Follow Rivers","artist":"Tiësto","album":"Prismatic: Pack One","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273c35939b4a4fffb5103786d7c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:07 raspamp volumio[984]: info: Exploding uri spotify:track:2UC24t5kdAucpeFtVONiYZ in service spop May 31 16:34:07 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:2UC24t5kdAucpeFtVONiYZ May 31 16:34:08 raspamp volumio[984]: info: Exploding uri spotify:track:3x3K1RP3Zfi2qeMR8kyrNO in service spop May 31 16:34:08 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3x3K1RP3Zfi2qeMR8kyrNO May 31 16:34:08 raspamp volumio[984]: info: Exploding uri spotify:track:3vE9tuRoHiyNPG5HJQLgNd in service spop May 31 16:34:08 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3vE9tuRoHiyNPG5HJQLgNd May 31 16:34:08 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:08 raspamp volumio[984]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::ClearQueue May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::stop May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::clearPlayQueue May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::saveQueue May 31 16:34:08 raspamp volumio[984]: info: CoreCommandRouter::volumioPushQueue May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::addQueueItems May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::addQueueItems May 31 16:34:08 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:08 raspamp volumio[984]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DWVuV87wUBNwc May 31 16:34:08 raspamp volumio[984]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DWVuV87wUBNwc in service spop May 31 16:34:08 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DWVuV87wUBNwc May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qlYaRFGvgR6afy1f4BBwD","service":"spop","name":"NANANI NANANA","artist":"Gazo","album":"APOCALYPSE","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b273dfc950ea8ef5cf436cf1c6d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2UC24t5kdAucpeFtVONiYZ","service":"spop","name":"Lola (feat. Nicola Sirkis)","artist":"Superbus","album":"Lola (feat. Nicola Sirkis)","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273bee7e524cbe791bf2429893f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AWDeHLc88XogCaCnZQLVI","service":"spop","name":"Cry For Me","artist":"The Weeknd","album":"Hurry Up Tomorrow","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273982320da137d0de34410df61","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xX6zmaXeKzB4u9TiM1XgU","service":"spop","name":"WE PRAY","artist":"Coldplay","album":"Moon Music (Full Moon Edition)","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b273ed4cd21086be1931a5b9d2c9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3x3K1RP3Zfi2qeMR8kyrNO","service":"spop","name":"Sorry I'm Here For Someone Else","artist":"Benson Boone","album":"Sorry I'm Here For Someone Else","type":"song","duration":156,"albumart":"https://i.scdn.co/image/ab67616d0000b27355bf59fe2cd3437613d031c7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3vE9tuRoHiyNPG5HJQLgNd","service":"spop","name":"Pélican","artist":"L2B","album":"Pélican","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273376ba192df0fd4d5d239bfe1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:08 raspamp volumio[984]: info: CoreCommandRouter::volumioPushQueue May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::saveQueue May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::updateTrackBlock May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::getTrackBlock May 31 16:34:08 raspamp volumio[984]: info: CoreCommandRouter::volumioPlay May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::play index 0 May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::stop May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::play index undefined May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:08 raspamp volumio[984]: info: CoreStateMachine::startPlaybackTimer May 31 16:34:08 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:08 raspamp volumio[984]: info: [1748702048912] ControllerSpotify::clearAddPlayTrack May 31 16:34:08 raspamp volumio[984]: info: Sending Spotify command with payload to local API: /player/play May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="resolved context of track" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=trace msg="emitting websocket event: will_play" May 31 16:34:09 raspamp volumio[984]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","play_origin":"go-librespot"}} May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="selected format OGG_VORBIS_320 (433d699d990857192e839a0cf197d0a5e7bd6ee5)" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="requested aes key for file 433d699d990857192e839a0cf197d0a5e7bd6ee5, gid: 0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:09 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:09 raspamp volumio[984]: info: Listing playlists May 31 16:34:09 raspamp volumio[984]: info: Listing playlists May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="fetched first chunk of 15, total size is 7540857 bytes" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=info msg="loaded track \"Azizam\" (paused: false, position: 0ms, duration: 162029ms, prefetched: false)" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=trace msg="scheduling prefetch in 132s" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=trace msg="emitting websocket event: metadata" May 31 16:34:09 raspamp volumio[984]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","name":"Azizam","artist_names":["Ed Sheeran"],"album_name":"Azizam","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d29a003b6e8d4c1c47bd4da4","position":0,"duration":162029,"release_date":"year:2025 month:4 day:4","track_number":1,"disc_number":1}} May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:09 raspamp go-librespot[1213]: time="2025-05-31T16:34:09+02:00" level=trace msg="emitting websocket event: playing" May 31 16:34:09 raspamp volumio[984]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","play_origin":"go-librespot"}} May 31 16:34:09 raspamp volumio[984]: SPOTIFY: PUSH STATE SPOTIFY May 31 16:34:09 raspamp volumio[984]: SPOTIFY: {"status":"play","service":"spop","title":"Azizam","artist":"Ed Sheeran","album":"Azizam","albumart":"https://i.scdn.co/image/ab67616d00001e02d29a003b6e8d4c1c47bd4da4","uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","trackType":"spotify","seek":0,"duration":162,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:09 raspamp volumio[984]: info: CoreCommandRouter::servicePushState May 31 16:34:09 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:09 raspamp volumio[984]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Azizam","artist":"Ed Sheeran","album":"Azizam","albumart":"https://i.scdn.co/image/ab67616d00001e02d29a003b6e8d4c1c47bd4da4","uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","trackType":"spotify","seek":0,"duration":162,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:09 raspamp volumio[984]: verbose: CURRENT POSITION 0 May 31 16:34:09 raspamp volumio[984]: info: CoreStateMachine::syncState stateService play May 31 16:34:09 raspamp volumio[984]: info: CoreStateMachine::syncState currentStatus stop May 31 16:34:10 raspamp go-librespot[1213]: time="2025-05-31T16:34:10+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:10 raspamp go-librespot[1213]: time="2025-05-31T16:34:10+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:10 raspamp volumio[984]: SPOTIFY: PUSH STATE SPOTIFY May 31 16:34:10 raspamp volumio[984]: SPOTIFY: {"status":"play","service":"spop","title":"Azizam","artist":"Ed Sheeran","album":"Azizam","albumart":"https://i.scdn.co/image/ab67616d00001e02d29a003b6e8d4c1c47bd4da4","uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","trackType":"spotify","seek":0,"duration":162,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::servicePushState May 31 16:34:10 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:10 raspamp volumio[984]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Azizam","artist":"Ed Sheeran","album":"Azizam","albumart":"https://i.scdn.co/image/ab67616d00001e02d29a003b6e8d4c1c47bd4da4","uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","trackType":"spotify","seek":0,"duration":162,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:10 raspamp volumio[984]: verbose: CURRENT POSITION 0 May 31 16:34:10 raspamp volumio[984]: info: CoreStateMachine::syncState stateService play May 31 16:34:10 raspamp volumio[984]: info: CoreStateMachine::syncState currentStatus play May 31 16:34:10 raspamp volumio[984]: info: Received an update from plugin. extracting info from payload May 31 16:34:10 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:10 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:10 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:10 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:10 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:10 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:10 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:10 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:10 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:10 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:10 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:10 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:10 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:19 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:19 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:19 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:34:19 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:34:19 raspamp volumio[984]: info: Discovery: Getting this device information May 31 16:34:19 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:19 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:19 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:34:20 raspamp go-librespot[1213]: time="2025-05-31T16:34:20+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:29 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:29 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:29 raspamp volumio[984]: info: Listing playlists May 31 16:34:29 raspamp volumio[984]: info: Listing playlists May 31 16:34:31 raspamp go-librespot[1213]: time="2025-05-31T16:34:31+02:00" level=debug msg="fetched chunk 5/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:31 raspamp go-librespot[1213]: time="2025-05-31T16:34:31+02:00" level=trace msg="sent dealer ping" May 31 16:34:31 raspamp go-librespot[1213]: time="2025-05-31T16:34:31+02:00" level=trace msg="received dealer pong" May 31 16:34:39 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:39 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:42 raspamp go-librespot[1213]: time="2025-05-31T16:34:42+02:00" level=debug msg="fetched chunk 6/14, size: 524288" uri="spotify:track:0GRc3eGTg8HBdWLRGYgqIc" May 31 16:34:49 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:49 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:49 raspamp volumio[984]: info: Listing playlists May 31 16:34:49 raspamp volumio[984]: info: Listing playlists May 31 16:34:49 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 16:34:49 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 16:34:49 raspamp volumio[984]: info: Discovery: Getting this device information May 31 16:34:49 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:49 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:49 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 16:34:52 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioReplaceandPlayItems May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::ClearQueue May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::stop May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::stPlaybackTimer May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::updateTrackBlock May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrackBlock May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:52 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:52 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::serviceStop May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrack 0 May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::serviceStop May 31 16:34:52 raspamp volumio[984]: info: Spotify Stop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: SPOTIFY STOP May 31 16:34:52 raspamp volumio[984]: SPOTIFY: {"status":"play","position":0,"title":"Azizam","artist":"Ed Sheeran","album":"Azizam","albumart":"https://i.scdn.co/image/ab67616d0000b273d29a003b6e8d4c1c47bd4da4","uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","trackType":"spotify","codec":"ogg","seek":0,"duration":162,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":53,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} May 31 16:34:52 raspamp volumio[984]: info: Sending Spotify command to local API: /player/pause May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::clearPlayQueue May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::saveQueue May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioPushQueue May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::addQueueItems May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::addQueueItems May 31 16:34:52 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:5lXLzaZoj4j4hfCYO687bN May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:5lXLzaZoj4j4hfCYO687bN May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioPushQueue May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::saveQueue May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::updateTrackBlock May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrackBlock May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioPlay May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::play index 1 May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::addQueueItems May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::addQueueItems May 31 16:34:52 raspamp volumio[984]: info: Preload queue cleared May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1musbempyJAw5gfSKZHXP9 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1musbempyJAw5gfSKZHXP9 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:4TwEdnSiTPDR1vg1QZ5K8W May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0TYTQ9vAJM0q4qT1mzDeFq May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0TYTQ9vAJM0q4qT1mzDeFq May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0fltq9Zz9JUWU3NMqRXFY0 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0fltq9Zz9JUWU3NMqRXFY0 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6xZCDUaY4XzlhCSHKSW7rf May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6xZCDUaY4XzlhCSHKSW7rf May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6PcVlezWXunO2Bh78iEq0W May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6PcVlezWXunO2Bh78iEq0W May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1wrVWI9VExFCUFl8c4cWKI May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1wrVWI9VExFCUFl8c4cWKI May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:726SEjbF3ToEbYbn43gnxl May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:726SEjbF3ToEbYbn43gnxl May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6JFw2mbcNfoFXnrk6AxGK6 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6JFw2mbcNfoFXnrk6AxGK6 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:5ZLUm9eab8y3tqQ1OhQSHI May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:5ZLUm9eab8y3tqQ1OhQSHI May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:7dzuYJwwtNnfcptmbD58aJ May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:7dzuYJwwtNnfcptmbD58aJ May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:7p9nV83OAXMpeAXx9SXW5i May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:7p9nV83OAXMpeAXx9SXW5i May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1iJ9cSEWYQsd2M7ROFyIZx May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1iJ9cSEWYQsd2M7ROFyIZx May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:7wLTLminmuEDTdLbCapAMV May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:7wLTLminmuEDTdLbCapAMV May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6xOiC7SgWCFsipcM69jIDO May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6xOiC7SgWCFsipcM69jIDO May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3BntzKsnLf2pc0dsvE3Zav May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3BntzKsnLf2pc0dsvE3Zav May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0wz1wXKDBtlGhfRNpnLtc1 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0wz1wXKDBtlGhfRNpnLtc1 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3sK8wGT43QFpWrvNQsrQya May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3sK8wGT43QFpWrvNQsrQya May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1UQtL1gwHWntLwWowiXB4C May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1UQtL1gwHWntLwWowiXB4C May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6zBRSNNizxsGWRQFg2eToG May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6zBRSNNizxsGWRQFg2eToG May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6WatFBLVB0x077xWeoVc2k May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6WatFBLVB0x077xWeoVc2k May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3PAkE2potAfVGgTocmrF89 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3PAkE2potAfVGgTocmrF89 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:72RjC359lcwhf1cON9ppls May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:72RjC359lcwhf1cON9ppls May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0hQqfUxxTVwYW172Ble1oD May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0hQqfUxxTVwYW172Ble1oD May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:14cxJ5uLXafpMWPAsJgEYt May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:14cxJ5uLXafpMWPAsJgEYt May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:79KzEOgXmAIG6yez6MxKdQ May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:79KzEOgXmAIG6yez6MxKdQ May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:2z9FUHRkbkV4BObNzbHsFU May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:2z9FUHRkbkV4BObNzbHsFU May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1aLHvqPB5CxGYzhJB7Hzo1 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1aLHvqPB5CxGYzhJB7Hzo1 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:1aGXmfXBq4koas7v3277P3 May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:1aGXmfXBq4koas7v3277P3 May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:61U7ZDXC4f5iKatYBwSegE May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:61U7ZDXC4f5iKatYBwSegE May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:6xX6zmaXeKzB4u9TiM1XgU May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:6xX6zmaXeKzB4u9TiM1XgU May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0qlYaRFGvgR6afy1f4BBwD May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:0qlYaRFGvgR6afy1f4BBwD May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3AWDeHLc88XogCaCnZQLVI May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3AWDeHLc88XogCaCnZQLVI May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:2UC24t5kdAucpeFtVONiYZ May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:2UC24t5kdAucpeFtVONiYZ May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3x3K1RP3Zfi2qeMR8kyrNO May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3x3K1RP3Zfi2qeMR8kyrNO May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3vE9tuRoHiyNPG5HJQLgNd May 31 16:34:52 raspamp volumio[984]: info: Using cached record of: spotify:track:3vE9tuRoHiyNPG5HJQLgNd May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:5SxahezRlC0saXbCALfB7c May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:5SxahezRlC0saXbCALfB7c in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:5SxahezRlC0saXbCALfB7c May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:7s3KNhg90zZhRewUTie2AS May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:7s3KNhg90zZhRewUTie2AS in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:7s3KNhg90zZhRewUTie2AS May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:08VceLSSJJ83Yz3NTCyxZC May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:08VceLSSJJ83Yz3NTCyxZC in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:08VceLSSJJ83Yz3NTCyxZC May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:32cwemsMJdzzsOYjVzfYMS May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:32cwemsMJdzzsOYjVzfYMS in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:32cwemsMJdzzsOYjVzfYMS May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:0hh94JJOOvvgugZUWWQxcr May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:0hh94JJOOvvgugZUWWQxcr in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:0hh94JJOOvvgugZUWWQxcr May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:4TRZpw82B3eOXKTmh5NiED May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:4TRZpw82B3eOXKTmh5NiED in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:4TRZpw82B3eOXKTmh5NiED May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:7ne4VBA60CxGM75vw0EYad in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:7ne4VBA60CxGM75vw0EYad May 31 16:34:52 raspamp volumio[984]: info: Adding Item to queue: spotify:track:3bTdmYAMKIvDLhxYDy9xHU May 31 16:34:52 raspamp volumio[984]: info: Exploding uri spotify:track:3bTdmYAMKIvDLhxYDy9xHU in service spop May 31 16:34:52 raspamp volumio[984]: SPOTIFY: EXPLODING URI:spotify:track:3bTdmYAMKIvDLhxYDy9xHU May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::stop May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::play index undefined May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:52 raspamp volumio[984]: info: CoreStateMachine::startPlaybackTimer May 31 16:34:52 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:34:52 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 16:34:52 raspamp volumio[984]: info: [1748702092924] ControllerSpotify::clearAddPlayTrack May 31 16:34:52 raspamp volumio[984]: info: Sending Spotify command with payload to local API: /player/play May 31 16:34:52 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="pause track at 43092ms" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="resolved context of track" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="emitting websocket event: will_play" May 31 16:34:53 raspamp volumio[984]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","play_origin":"go-librespot"}} May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3bTdmYAMKIvDLhxYDy9xHU","service":"spop","name":"Ma faute","artist":"Marine","album":"Ma faute","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b2732c594ec3fd95c7ed0448ced1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4TRZpw82B3eOXKTmh5NiED","service":"spop","name":"Ce qui me va","artist":"Pierre Garnier","album":"Chaque seconde","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b273309ead68a56fdbc235575eb0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5SxahezRlC0saXbCALfB7c","service":"spop","name":"End of the World","artist":"Miley Cyrus","album":"End of the World","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b2734545d0b1ca149d97eb22bfe0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0hh94JJOOvvgugZUWWQxcr","service":"spop","name":"A Girl Like You","artist":"FDVM","album":"A Girl Like You","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b273f6252d9d7164b77a080df843","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:32cwemsMJdzzsOYjVzfYMS","service":"spop","name":"Mauvais Garçon","artist":"Helena","album":"Mauvais Garçon","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b2731ad45d8cc9f48fc3c2dcae5d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="selected format OGG_VORBIS_320 (51ee67fabb55e490cb08efce623704e5d658f8be)" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="requested aes key for file 51ee67fabb55e490cb08efce623704e5d658f8be, gid: 5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:08VceLSSJJ83Yz3NTCyxZC","service":"spop","name":"Exotica","artist":"Gabry Ponte","album":"Exotica","type":"song","duration":121,"albumart":"https://i.scdn.co/image/ab67616d0000b2732a44597c7d4271d77db8f966","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7s3KNhg90zZhRewUTie2AS","service":"spop","name":"Hallelujah - Live à Notre-Dame","artist":"Vianney","album":"Hallelujah (Live à Notre-Dame)","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273425739cdd14fe4df6dd9b170","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 31 16:34:53 raspamp volumio[984]: 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"}] May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::volumioPushQueue May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::saveQueue May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::updateTrackBlock May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::getTrackBlock May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="fetched first chunk of 15, total size is 7737893 bytes" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=info msg="loaded track \"NINAO\" (paused: false, position: 0ms, duration: 167813ms, prefetched: false)" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="scheduling prefetch in 138s" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="emitting websocket event: metadata" May 31 16:34:53 raspamp volumio[984]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","name":"NINAO","artist_names":["GIMS"],"album_name":"NINAO","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","position":0,"duration":167813,"release_date":"year:2025 month:2 day:20","track_number":1,"disc_number":1}} May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="emitting websocket event: paused" May 31 16:34:53 raspamp volumio[984]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","play_origin":"go-librespot"}} May 31 16:34:53 raspamp volumio[984]: SPOTIFY: PUSH STATE SPOTIFY May 31 16:34:53 raspamp volumio[984]: SPOTIFY: {"status":"pause","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::servicePushState May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:53 raspamp volumio[984]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:53 raspamp volumio[984]: verbose: CURRENT POSITION 1 May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::syncState stateService pause May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::syncState currentStatus stop May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:53 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:53 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:53 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=trace msg="emitting websocket event: playing" May 31 16:34:53 raspamp volumio[984]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","play_origin":"go-librespot"}} May 31 16:34:53 raspamp volumio[984]: SPOTIFY: PUSH STATE SPOTIFY May 31 16:34:53 raspamp volumio[984]: SPOTIFY: {"status":"play","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:53 raspamp volumio[984]: info: CoreCommandRouter::servicePushState May 31 16:34:53 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:53 raspamp volumio[984]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:53 raspamp volumio[984]: verbose: CURRENT POSITION 1 May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::syncState stateService play May 31 16:34:53 raspamp volumio[984]: info: CoreStateMachine::syncState currentStatus stop May 31 16:34:53 raspamp go-librespot[1213]: time="2025-05-31T16:34:53+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:54 raspamp go-librespot[1213]: time="2025-05-31T16:34:54+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:34:54 raspamp volumio[984]: SPOTIFY: PUSH STATE SPOTIFY May 31 16:34:54 raspamp volumio[984]: SPOTIFY: {"status":"play","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::servicePushState May 31 16:34:54 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:54 raspamp volumio[984]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"NINAO","artist":"GIMS","album":"NINAO","albumart":"https://i.scdn.co/image/ab67616d00001e02ddfe430ec4417e80852f6142","uri":"spotify:track:5lXLzaZoj4j4hfCYO687bN","trackType":"spotify","seek":0,"duration":167,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 31 16:34:54 raspamp volumio[984]: verbose: CURRENT POSITION 1 May 31 16:34:54 raspamp volumio[984]: info: CoreStateMachine::syncState stateService play May 31 16:34:54 raspamp volumio[984]: info: CoreStateMachine::syncState currentStatus play May 31 16:34:54 raspamp volumio[984]: info: Received an update from plugin. extracting info from payload May 31 16:34:54 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:54 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:54 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:54 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:54 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:54 raspamp volumio[984]: info: CoreStateMachine::pushState May 31 16:34:54 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::volumioPushState May 31 16:34:54 raspamp volumio[984]: info: MRS: Pushing multiroomSync output update for this device May 31 16:34:54 raspamp volumio[984]: info: MRS: Pushing multiroomSync output May 31 16:34:54 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:54 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:34:54 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:54 raspamp volumio[984]: SPOTIFY: RECEIVED VOLUMIO VOLUME 53 May 31 16:34:59 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:34:59 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:35:01 raspamp go-librespot[1213]: time="2025-05-31T16:35:01+02:00" level=trace msg="sent dealer ping" May 31 16:35:01 raspamp go-librespot[1213]: time="2025-05-31T16:35:01+02:00" level=trace msg="received dealer pong" May 31 16:35:04 raspamp go-librespot[1213]: time="2025-05-31T16:35:04+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:5lXLzaZoj4j4hfCYO687bN" May 31 16:35:06 raspamp volumio[984]: verbose: New Socket.io Connection to 192.168.72.109 from 192.168.72.48 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 May 31 16:35:06 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 16:35:06 raspamp volumio[984]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 31 16:35:06 raspamp volumio[984]: info: CoreCommandRouter::volumioGetState May 31 16:35:06 raspamp volumio[984]: info: CorePlayQueue::getTrack 1 May 31 16:35:06 raspamp volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources May 31 16:35:06 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 16:35:06 raspamp volumio[984]: info: CoreCommandRouter::volumioGetQueue May 31 16:35:06 raspamp volumio[984]: info: CoreStateMachine::getQueue May 31 16:35:06 raspamp volumio[984]: info: CorePlayQueue::getQueue May 31 16:35:06 raspamp volumio[984]: info: Listing playlists May 31 16:35:08 raspamp volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 31 16:35:08 raspamp volumio[984]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 31 16:35:08 raspamp volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:35:08 raspamp volumio[984]: TypeError: Cannot read property 'then' of undefined May 31 16:35:08 raspamp volumio[984]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 31 16:35:08 raspamp volumio[984]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 31 16:35:08 raspamp volumio[984]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 31 16:35:08 raspamp volumio[984]: at Socket.emit (events.js:315:20) May 31 16:35:08 raspamp volumio[984]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 16:35:08 raspamp volumio[984]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 31 16:35:08 raspamp volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 16:35:09 raspamp sudo[8078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 16:34 May 31 16:35:09 raspamp sudo[8078]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"