Feb 24 17:45:00 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:1aj9VZXv0bDLyqC5GgnYxh Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0uMhv1Ew3HwLoMl58cdJgF Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:2LrEQj5JLvzguxk0CW6dYb Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0MCZA8zF2TW4jZKtu9glmW Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:7fhqMm50TdMaPz5PYYEni2 Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:3toY7RkmEaD9ueEDUv6wlt Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0v2COoBGBQoMLQ7GOtHNHy Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0qpMmGVUwg8AjPuqpm9vRs Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:5pGdTRyqaffV36mXWbWkvU Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0ZWTkR8Nuq3JsLF70lMHcF Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:3abCqikxdShjyeFyU2IGyA Feb 24 17:45:00 lenja-box volumio[1282]: info: Preloading song: spotify:track:0xTdh99C8EzCd9851r8Ov8 Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:1aj9VZXv0bDLyqC5GgnYxh in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:1aj9VZXv0bDLyqC5GgnYxh Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0uMhv1Ew3HwLoMl58cdJgF in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0uMhv1Ew3HwLoMl58cdJgF Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:2LrEQj5JLvzguxk0CW6dYb in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:2LrEQj5JLvzguxk0CW6dYb Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0MCZA8zF2TW4jZKtu9glmW in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0MCZA8zF2TW4jZKtu9glmW Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1aj9VZXv0bDLyqC5GgnYxh","service":"spop","name":"Das alt raue Kreuz","artist":"George Bennard","album":"Lieder der Gnade","type":"song","duration":295,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box go-librespot[1841]: time="2026-02-24T17:45:00+01:00" level=trace msg="sent dealer ping" Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:7fhqMm50TdMaPz5PYYEni2 in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:7fhqMm50TdMaPz5PYYEni2 Feb 24 17:45:00 lenja-box go-librespot[1841]: time="2026-02-24T17:45:00+01:00" level=trace msg="received dealer pong" Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:3toY7RkmEaD9ueEDUv6wlt in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:3toY7RkmEaD9ueEDUv6wlt Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0uMhv1Ew3HwLoMl58cdJgF","service":"spop","name":"Gott der Gnade","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LrEQj5JLvzguxk0CW6dYb","service":"spop","name":"Gethsemane","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0v2COoBGBQoMLQ7GOtHNHy in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0v2COoBGBQoMLQ7GOtHNHy Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0MCZA8zF2TW4jZKtu9glmW","service":"spop","name":"Schau ich zu deinem Kreuze hin","artist":"James Koerts","album":"Lieder der Gnade","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0qpMmGVUwg8AjPuqpm9vRs in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0qpMmGVUwg8AjPuqpm9vRs Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:5pGdTRyqaffV36mXWbWkvU in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:5pGdTRyqaffV36mXWbWkvU Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7fhqMm50TdMaPz5PYYEni2","service":"spop","name":"O, die tiefe Liebe Jesu","artist":"Bob Kauflin","album":"Lieder der Gnade","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3toY7RkmEaD9ueEDUv6wlt","service":"spop","name":"Grosser Gott","artist":"Bob Kauflin","album":"Lieder der Gnade","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0ZWTkR8Nuq3JsLF70lMHcF in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0ZWTkR8Nuq3JsLF70lMHcF Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:3abCqikxdShjyeFyU2IGyA in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:3abCqikxdShjyeFyU2IGyA Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0v2COoBGBQoMLQ7GOtHNHy","service":"spop","name":"Welch grosse Weisheit","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qpMmGVUwg8AjPuqpm9vRs","service":"spop","name":"Komm, du Quelle","artist":"John Wyeth","album":"Lieder der Gnade","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: info: Exploding uri spotify:track:0xTdh99C8EzCd9851r8Ov8 in service spop Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0xTdh99C8EzCd9851r8Ov8 Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0ZWTkR8Nuq3JsLF70lMHcF","service":"spop","name":"Herr aller Hoffnung","artist":"Traditional","album":"Lieder der Gnade","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3abCqikxdShjyeFyU2IGyA","service":"spop","name":"Volk Gottes, komm","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:00 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5pGdTRyqaffV36mXWbWkvU","service":"spop","name":"Sprich, o Herr","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:01 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0xTdh99C8EzCd9851r8Ov8","service":"spop","name":"Ein Thron, so hoch und wahr","artist":"Keith Getty","album":"Lieder der Gnade","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b27388e5dd8fad5f42833b0cc9d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:01 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:01 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:03 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:04 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 24 17:45:04 lenja-box volumio[1282]: info: In handleBrowseUri, curUri=spotify:album:3yGXDfuXYTkK9KoBsKRuJw Feb 24 17:45:05 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:3AFHGvYuHnoATCVSnqD3RU Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:3QkdmWyCmdO1kNpCYghmAV Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:0Szeb3iLk2wgp1iuDSvlU4 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:4cYjsiILaZqBiy65gYaixN Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:5R831Z2Na6ce6pQExUQ02j Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:75IUd22yB6i9j0L3LSzWbR Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:40FHYavIU8HJW1KqsbeJFk Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:12rARA1keZKFzaRVkQhOR2 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:79TjHcRlu63jM1l8NJLUXy Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:1vxaiUUFEEdTMTnBLHfYV6 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:4ezqnzeVnbn7Gj9OvD3ul9 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:2xcpBGRgMpGSHiEZp1vgVl Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:10QcCKsY6GbhefrFHZPzgC Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:0LWURdX3Taz0qKmH5BiYrn Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:40QVAnMDvMA39EmTRKOSqM Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:2QzBoRSblke4EbyXPbT8y8 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:0zNDZR3BM3jiv6Vl5jZYpz Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:3LcVX2ms535TWoSXW0L3Yr Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:0LC7NBhgPQyMNq8b6xHeP3 Feb 24 17:45:05 lenja-box volumio[1282]: info: Preloading song: spotify:track:4vYGrBALt7OH3QIhAuHPdm Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:3AFHGvYuHnoATCVSnqD3RU in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:3AFHGvYuHnoATCVSnqD3RU Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:3QkdmWyCmdO1kNpCYghmAV in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:3QkdmWyCmdO1kNpCYghmAV Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:0Szeb3iLk2wgp1iuDSvlU4 in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0Szeb3iLk2wgp1iuDSvlU4 Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:4cYjsiILaZqBiy65gYaixN in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:4cYjsiILaZqBiy65gYaixN Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:5R831Z2Na6ce6pQExUQ02j in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:5R831Z2Na6ce6pQExUQ02j Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:75IUd22yB6i9j0L3LSzWbR in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:75IUd22yB6i9j0L3LSzWbR Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3QkdmWyCmdO1kNpCYghmAV","service":"spop","name":"Teil 2 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:40FHYavIU8HJW1KqsbeJFk in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:40FHYavIU8HJW1KqsbeJFk Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:12rARA1keZKFzaRVkQhOR2 in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:12rARA1keZKFzaRVkQhOR2 Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","service":"spop","name":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Szeb3iLk2wgp1iuDSvlU4","service":"spop","name":"Teil 3 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:79TjHcRlu63jM1l8NJLUXy in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:79TjHcRlu63jM1l8NJLUXy Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4cYjsiILaZqBiy65gYaixN","service":"spop","name":"Teil 4 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:75IUd22yB6i9j0L3LSzWbR","service":"spop","name":"Teil 6 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":282,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:1vxaiUUFEEdTMTnBLHfYV6 in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:1vxaiUUFEEdTMTnBLHfYV6 Feb 24 17:45:05 lenja-box volumio[1282]: info: Exploding uri spotify:track:4ezqnzeVnbn7Gj9OvD3ul9 in service spop Feb 24 17:45:05 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:4ezqnzeVnbn7Gj9OvD3ul9 Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:2xcpBGRgMpGSHiEZp1vgVl in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:2xcpBGRgMpGSHiEZp1vgVl Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5R831Z2Na6ce6pQExUQ02j","service":"spop","name":"Teil 5 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:12rARA1keZKFzaRVkQhOR2","service":"spop","name":"Teil 8 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:10QcCKsY6GbhefrFHZPzgC in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:10QcCKsY6GbhefrFHZPzgC Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79TjHcRlu63jM1l8NJLUXy","service":"spop","name":"Teil 9 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1vxaiUUFEEdTMTnBLHfYV6","service":"spop","name":"Teil 10 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:0LWURdX3Taz0qKmH5BiYrn in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0LWURdX3Taz0qKmH5BiYrn Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:40QVAnMDvMA39EmTRKOSqM in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:40QVAnMDvMA39EmTRKOSqM Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ezqnzeVnbn7Gj9OvD3ul9","service":"spop","name":"Teil 11 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:40FHYavIU8HJW1KqsbeJFk","service":"spop","name":"Teil 7 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:2QzBoRSblke4EbyXPbT8y8 in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:2QzBoRSblke4EbyXPbT8y8 Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:10QcCKsY6GbhefrFHZPzgC","service":"spop","name":"Teil 13 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:0zNDZR3BM3jiv6Vl5jZYpz in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0zNDZR3BM3jiv6Vl5jZYpz Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2xcpBGRgMpGSHiEZp1vgVl","service":"spop","name":"Teil 12 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0LWURdX3Taz0qKmH5BiYrn","service":"spop","name":"Teil 14 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:3LcVX2ms535TWoSXW0L3Yr in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:3LcVX2ms535TWoSXW0L3Yr Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:0LC7NBhgPQyMNq8b6xHeP3 in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:0LC7NBhgPQyMNq8b6xHeP3 Feb 24 17:45:06 lenja-box volumio[1282]: info: Exploding uri spotify:track:4vYGrBALt7OH3QIhAuHPdm in service spop Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: EXPLODING URI:spotify:track:4vYGrBALt7OH3QIhAuHPdm Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:06 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:06 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QzBoRSblke4EbyXPbT8y8","service":"spop","name":"Teil 16 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0zNDZR3BM3jiv6Vl5jZYpz","service":"spop","name":"Teil 17 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3LcVX2ms535TWoSXW0L3Yr","service":"spop","name":"Teil 18 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:40QVAnMDvMA39EmTRKOSqM","service":"spop","name":"Teil 15 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0LC7NBhgPQyMNq8b6xHeP3","service":"spop","name":"Teil 19 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:06 lenja-box volumio[1282]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4vYGrBALt7OH3QIhAuHPdm","service":"spop","name":"Teil 20 & Outro - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":90,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 24 17:45:07 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:07 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:07 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:07 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:07 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:45:07 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:45:10 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::ClearQueue Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::stop Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::updateTrackBlock Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::getTrackBlock Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::stPlaybackTimer Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 9 Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:10 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:10 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 9 Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::serviceStop Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 9 Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::serviceStop Feb 24 17:45:10 lenja-box volumio[1282]: info: Spotify Stop Feb 24 17:45:10 lenja-box volumio[1282]: SPOTIFY: SPOTIFY STOP Feb 24 17:45:10 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","position":9,"title":"36 - Der rätselhafte Brandstifter - Teil 08","artist":"TKKG Junior","album":"Folge 36: Der rätselhafte Brandstifter","albumart":"https://i.scdn.co/image/ab67616d0000b2730047eff9f5b5efe93671f5b1","uri":"spotify:track:5mYlqF5tCl8R4USf1ixRZG","trackType":"spotify","codec":"ogg","seek":66365,"duration":181,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":86,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 24 17:45:10 lenja-box volumio[1282]: info: Sending Spotify command to local API: /player/pause Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::clearPlayQueue Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::saveQueue Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushQueue Feb 24 17:45:10 lenja-box volumio[1282]: info: CoreStateMachine::addQueueItems Feb 24 17:45:10 lenja-box volumio[1282]: info: CorePlayQueue::addQueueItems Feb 24 17:45:10 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:45:10 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:3AFHGvYuHnoATCVSnqD3RU Feb 24 17:45:10 lenja-box volumio[1282]: info: Using cached record of: spotify:track:3AFHGvYuHnoATCVSnqD3RU Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushQueue Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::saveQueue Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::updateTrackBlock Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::getTrackBlock Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 9 Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPlay Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::play index 0 Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::addQueueItems Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::addQueueItems Feb 24 17:45:11 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:3QkdmWyCmdO1kNpCYghmAV Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:3QkdmWyCmdO1kNpCYghmAV Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:0Szeb3iLk2wgp1iuDSvlU4 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:0Szeb3iLk2wgp1iuDSvlU4 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:4cYjsiILaZqBiy65gYaixN Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:4cYjsiILaZqBiy65gYaixN Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:5R831Z2Na6ce6pQExUQ02j Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:5R831Z2Na6ce6pQExUQ02j Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:75IUd22yB6i9j0L3LSzWbR Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:75IUd22yB6i9j0L3LSzWbR Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:40FHYavIU8HJW1KqsbeJFk Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:40FHYavIU8HJW1KqsbeJFk Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:12rARA1keZKFzaRVkQhOR2 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:12rARA1keZKFzaRVkQhOR2 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:79TjHcRlu63jM1l8NJLUXy Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:79TjHcRlu63jM1l8NJLUXy Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:1vxaiUUFEEdTMTnBLHfYV6 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:1vxaiUUFEEdTMTnBLHfYV6 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:4ezqnzeVnbn7Gj9OvD3ul9 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:4ezqnzeVnbn7Gj9OvD3ul9 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:2xcpBGRgMpGSHiEZp1vgVl Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:2xcpBGRgMpGSHiEZp1vgVl Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:10QcCKsY6GbhefrFHZPzgC Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:10QcCKsY6GbhefrFHZPzgC Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:0LWURdX3Taz0qKmH5BiYrn Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:0LWURdX3Taz0qKmH5BiYrn Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:40QVAnMDvMA39EmTRKOSqM Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:40QVAnMDvMA39EmTRKOSqM Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:2QzBoRSblke4EbyXPbT8y8 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:2QzBoRSblke4EbyXPbT8y8 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:0zNDZR3BM3jiv6Vl5jZYpz Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:0zNDZR3BM3jiv6Vl5jZYpz Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:3LcVX2ms535TWoSXW0L3Yr Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:3LcVX2ms535TWoSXW0L3Yr Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:0LC7NBhgPQyMNq8b6xHeP3 Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:0LC7NBhgPQyMNq8b6xHeP3 Feb 24 17:45:11 lenja-box volumio[1282]: info: Adding Item to queue: spotify:track:4vYGrBALt7OH3QIhAuHPdm Feb 24 17:45:11 lenja-box volumio[1282]: info: Using cached record of: spotify:track:4vYGrBALt7OH3QIhAuHPdm Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::stop Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushQueue Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::saveQueue Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::play index undefined Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::updateTrackBlock Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::getTrackBlock Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:11 lenja-box volumio[1282]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:45:11 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:11 lenja-box volumio[1282]: info: [1771951511067] ControllerSpotify::clearAddPlayTrack Feb 24 17:45:11 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/play Feb 24 17:45:11 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="renewing login5 access token" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=info msg="authenticated Login5" username="31************************zq" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="resolved context of track" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=trace msg="emitting websocket event: will_play" Feb 24 17:45:11 lenja-box volumio[1282]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","play_origin":"go-librespot"}} Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1366" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="selected format OGG_VORBIS_320 (acdc24e7475e354c23a47c3b62d229ef9c3c0e91)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=debug msg="requested aes key for file acdc24e7475e354c23a47c3b62d229ef9c3c0e91, gid: 3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:11 lenja-box go-librespot[1841]: time="2026-02-24T17:45:11+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:12 lenja-box go-librespot[1841]: time="2026-02-24T17:45:12+01:00" level=debug msg="fetched first chunk of 16, total size is 8016468 bytes" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:12 lenja-box go-librespot[1841]: time="2026-02-24T17:45:12+01:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01: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, PCM format = FLOAT_LE" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=info msg="loaded track \"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall\" (paused: false, position: 1ms, duration: 197285ms, prefetched: false)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=trace msg="scheduling prefetch in 167s" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=trace msg="emitting websocket event: metadata" Feb 24 17:45:13 lenja-box volumio[1282]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","name":"Inhaltsangabe \u0026 Teil 1 - 21: Verwechslung im Stall","artist_names":["Emmi - Mutmachgeschichten für Kinder"],"album_name":"21: Verwechslung im Stall","album_cover_url":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","position":1,"duration":197285,"release_date":"year:2025 month:1 day:17","track_number":1,"disc_number":1}} Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=trace msg="emitting websocket event: playing" Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=debug msg="pause track at 71ms" Feb 24 17:45:13 lenja-box volumio[1282]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","resume":false,"play_origin":"go-librespot"}} Feb 24 17:45:13 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:45:13 lenja-box volumio[1282]: SPOTIFY: {"status":"play","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:13 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:45:13 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:13 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:13 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:45:13 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService play Feb 24 17:45:13 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus stop Feb 24 17:45:13 lenja-box go-librespot[1841]: time="2026-02-24T17:45:13+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:14 lenja-box go-librespot[1841]: time="2026-02-24T17:45:14+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:14 lenja-box go-librespot[1841]: time="2026-02-24T17:45:14+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:14 lenja-box go-librespot[1841]: time="2026-02-24T17:45:14+01:00" level=trace msg="emitting websocket event: paused" Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","play_origin":"go-librespot"}} Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:14 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:14 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:14 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box go-librespot[1841]: time="2026-02-24T17:45:14+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":1,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:14 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:14 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:14 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:14 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:14 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:14 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 17:45:30 lenja-box go-librespot[1841]: time="2026-02-24T17:45:30+01:00" level=trace msg="sent dealer ping" Feb 24 17:45:30 lenja-box go-librespot[1841]: time="2026-02-24T17:45:30+01:00" level=trace msg="received dealer pong" Feb 24 17:45:42 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume+ Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 87 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Updating multiroomSync output Feb 24 17:45:42 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume+ Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 88 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 17:45:42 lenja-box volumio[1282]: info: Setting Spotify Volume from Volumio: 88 Feb 24 17:45:42 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume+ Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:42 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:42 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:42 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 89 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 88 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 89 Feb 24 17:45:42 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:43 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume- Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:43 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:43 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:43 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:43 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:43 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 88 Feb 24 17:45:43 lenja-box volumio[1282]: info: MRS: Updating multiroomSync output Feb 24 17:45:43 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume- Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:43 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:43 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:43 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:43 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:43 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:43 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 87 Feb 24 17:45:43 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 88 Feb 24 17:45:43 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 87 Feb 24 17:45:43 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:44 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume- Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:44 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:44 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:44 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:44 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 88 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 86 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 17:45:44 lenja-box volumio[1282]: info: Setting Spotify Volume from Volumio: 86 Feb 24 17:45:44 lenja-box volumio[1282]: info: VolumeController::SetAlsaVolume- Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:44 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:44 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:44 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:44 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:44 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:45:44 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:45 lenja-box volumio[1282]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Feb 24 17:45:45 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/volume Feb 24 17:45:45 lenja-box go-librespot[1841]: time="2026-02-24T17:45:45+01:00" level=debug msg="update volume requested to 56360/65535" Feb 24 17:45:46 lenja-box go-librespot[1841]: time="2026-02-24T17:45:46+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 24 17:45:46 lenja-box go-librespot[1841]: time="2026-02-24T17:45:46+01:00" level=trace msg="emitting websocket event: volume" Feb 24 17:45:46 lenja-box volumio[1282]: SPOTIFY: received: {"type":"volume","data":{"value":86,"max":100}} Feb 24 17:45:46 lenja-box volumio[1282]: SPOTIFY: RECEIVED SPOTIFY VOLUME 86 Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreCommandRouter::volumioSeek Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreStateMachine::seek Feb 24 17:45:48 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:48 lenja-box volumio[1282]: info: TRACKBLOCK {"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","service":"spop","name":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:45:48 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:48 lenja-box volumio[1282]: info: Spotify seek to: 68000 Feb 24 17:45:48 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:48 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:48 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:48 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:48 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:48 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:48 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:45:48 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:48 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:45:48 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:48 lenja-box go-librespot[1841]: time="2026-02-24T17:45:48+01:00" level=debug msg="seek track to 68000ms" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=trace msg="seek to 68000ms (diff: 120ms, samples: 2998800, bytes: 2775798)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":68000,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":68000,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:45:49 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:49 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":68000,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:49 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:49 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:49 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:49 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:49 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:49 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:45:49 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=debug msg="fetched chunk 7/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=debug msg="fetched chunk 6/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:49 lenja-box go-librespot[1841]: time="2026-02-24T17:45:49+01:00" level=debug msg="fetched chunk 8/15, size: 524288" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::volumioSeek Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::seek Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: TRACKBLOCK {"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","service":"spop","name":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: Spotify seek to: 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:50 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:50 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:45:50 lenja-box go-librespot[1841]: time="2026-02-24T17:45:50+01:00" level=debug msg="seek track to 0ms" Feb 24 17:45:50 lenja-box go-librespot[1841]: time="2026-02-24T17:45:50+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:45:50 lenja-box go-librespot[1841]: time="2026-02-24T17:45:50+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:45:50 lenja-box go-librespot[1841]: time="2026-02-24T17:45:50+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":0,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:45:50 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:45:50 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:45:50 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:45:50 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:45:50 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:45:50 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:00 lenja-box go-librespot[1841]: time="2026-02-24T17:46:00+01:00" level=trace msg="sent dealer ping" Feb 24 17:46:00 lenja-box go-librespot[1841]: time="2026-02-24T17:46:00+01:00" level=trace msg="received dealer pong" Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPrevious Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreStateMachine::previous Feb 24 17:46:02 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:02 lenja-box volumio[1282]: info: Spotify seek to: 0 Feb 24 17:46:02 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:46:02 lenja-box go-librespot[1841]: time="2026-02-24T17:46:02+01:00" level=debug msg="seek track to 0ms" Feb 24 17:46:02 lenja-box go-librespot[1841]: time="2026-02-24T17:46:02+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:02 lenja-box go-librespot[1841]: time="2026-02-24T17:46:02+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:02 lenja-box go-librespot[1841]: time="2026-02-24T17:46:02+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:46:02 lenja-box go-librespot[1841]: time="2026-02-24T17:46:02+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":0,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:46:02 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:02 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:02 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:02 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:02 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:02 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:02 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:02 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:02 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:04 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPrevious Feb 24 17:46:04 lenja-box volumio[1282]: info: CoreStateMachine::previous Feb 24 17:46:04 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPrevious Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreStateMachine::previous Feb 24 17:46:05 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPrevious Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreStateMachine::previous Feb 24 17:46:05 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: Spotify seek to: 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:46:05 lenja-box go-librespot[1841]: time="2026-02-24T17:46:05+01:00" level=debug msg="seek track to 0ms" Feb 24 17:46:05 lenja-box go-librespot[1841]: time="2026-02-24T17:46:05+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:05 lenja-box go-librespot[1841]: time="2026-02-24T17:46:05+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:05 lenja-box go-librespot[1841]: time="2026-02-24T17:46:05+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:46:05 lenja-box go-librespot[1841]: time="2026-02-24T17:46:05+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":0,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:46:05 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:05 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:05 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:05 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:05 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:05 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:05 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:05 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::volumioSeek Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::seek Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: TRACKBLOCK {"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","service":"spop","name":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: Spotify seek to: 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:09 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:09 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:09 lenja-box go-librespot[1841]: time="2026-02-24T17:46:09+01:00" level=debug msg="seek track to 0ms" Feb 24 17:46:09 lenja-box go-librespot[1841]: time="2026-02-24T17:46:09+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:09 lenja-box go-librespot[1841]: time="2026-02-24T17:46:09+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:09 lenja-box go-librespot[1841]: time="2026-02-24T17:46:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:46:09 lenja-box go-librespot[1841]: time="2026-02-24T17:46:09+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":0,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:09 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:09 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:09 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:09 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:09 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:09 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:29 lenja-box go-librespot[1841]: time="2026-02-24T17:46:29+01:00" level=trace msg="received accesspoint ping" Feb 24 17:46:29 lenja-box go-librespot[1841]: time="2026-02-24T17:46:29+01:00" level=trace msg="received accesspoint pong ack" Feb 24 17:46:30 lenja-box go-librespot[1841]: time="2026-02-24T17:46:30+01:00" level=trace msg="sent dealer ping" Feb 24 17:46:30 lenja-box go-librespot[1841]: time="2026-02-24T17:46:30+01:00" level=trace msg="received dealer pong" Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::volumioSeek Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::seek Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: TRACKBLOCK {"uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","service":"spop","name":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737e9e5485ddb6e16b230d6908","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"} Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::startPlaybackTimer Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: Spotify seek to: 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: Sending Spotify command with payload to local API: /player/seek Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:31 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:31 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:31 lenja-box go-librespot[1841]: time="2026-02-24T17:46:31+01:00" level=debug msg="seek track to 0ms" Feb 24 17:46:31 lenja-box go-librespot[1841]: time="2026-02-24T17:46:31+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:31 lenja-box go-librespot[1841]: time="2026-02-24T17:46:31+01:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:3AFHGvYuHnoATCVSnqD3RU" Feb 24 17:46:31 lenja-box go-librespot[1841]: time="2026-02-24T17:46:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 24 17:46:31 lenja-box go-librespot[1841]: time="2026-02-24T17:46:31+01:00" level=trace msg="emitting websocket event: seek" Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: received: {"type":"seek","data":{"context_uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","position":0,"duration":197285,"play_origin":"go-librespot"}} Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: PUSH STATE SPOTIFY Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::servicePushState Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Inhaltsangabe & Teil 1 - 21: Verwechslung im Stall","artist":"Emmi - Mutmachgeschichten für Kinder","album":"21: Verwechslung im Stall","albumart":"https://i.scdn.co/image/ab67616d00001e027e9e5485ddb6e16b230d6908","uri":"spotify:track:3AFHGvYuHnoATCVSnqD3RU","trackType":"spotify","seek":0,"duration":197,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 24 17:46:31 lenja-box volumio[1282]: verbose: CURRENT POSITION 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::syncState stateService pause Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::syncState currentStatus play Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreStateMachine::pushState Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::volumioPushState Feb 24 17:46:31 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output update for this device Feb 24 17:46:31 lenja-box volumio[1282]: info: MRS: Pushing multiroomSync output Feb 24 17:46:31 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetState Feb 24 17:46:31 lenja-box volumio[1282]: info: CorePlayQueue::getTrack 0 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: RECEIVED VOLUMIO VOLUME 85 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: SPOTIFY VOLUME 86 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: VOLUMIO VOLUME 85 Feb 24 17:46:31 lenja-box volumio[1282]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 24 17:46:47 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:47 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: mpd , search Feb 24 17:46:47 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: spop , search Feb 24 17:46:48 lenja-box volumio[1282]: info: All search sources collected, pushing search results Feb 24 17:46:48 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 24 17:46:48 lenja-box volumio[1282]: info: In handleBrowseUri, curUri=spotify:artist:5Mfu5smf7qXJZyUw9QzWXw Feb 24 17:46:49 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:3kT8VzsnwUYNB2JE09Illc Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:2OBxLPPBAqSCRHgyITZNQD Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:4XarXJEg5tkc5eFcxgXVnc Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:2R0CYBsMwBo88VJRuRyrNB Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:1hjlDL4XtSh1oekmk7wepI Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:3WHgAR7SO400OzH6rf3Yec Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:69xqedR9Ozntiemaf4fblg Feb 24 17:46:49 lenja-box volumio[1282]: info: Preloading song: spotify:track:4c4SoPUt9msmFZMXJNv9rn Feb 24 17:46:49 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:46:49 lenja-box volumio[1282]: info: Preload queue cleared Feb 24 17:46:50 lenja-box volumio[1282]: info: Executing endpoint getSimilarArtists Feb 24 17:46:50 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 24 17:46:50 lenja-box volumio[1282]: info: Executing endpoint getSimilarArtists Feb 24 17:46:50 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint getSimilarArtists Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint getSimilarArtists Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: Executing endpoint metavolumio Feb 24 17:46:51 lenja-box volumio[1282]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 24 17:46:53 lenja-box volumio[1282]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:46:53 lenja-box volumio[1282]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Feb 24 17:46:53 lenja-box volumio[1282]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Feb 24 17:46:53 lenja-box volumio[1282]: errno: -3007, Feb 24 17:46:53 lenja-box volumio[1282]: code: 'ENOTFOUND', Feb 24 17:46:53 lenja-box volumio[1282]: syscall: 'getaddrinfo', Feb 24 17:46:53 lenja-box volumio[1282]: hostname: 'cdn-images.dzcdn.net' Feb 24 17:46:53 lenja-box volumio[1282]: } Feb 24 17:46:53 lenja-box volumio[1282]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 17:46:58 lenja-box sudo[14474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 17:45' Feb 24 17:46:58 lenja-box sudo[14474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"