May 28 16:43:00 volumio volumio[8375]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 16:43:00 volumio volumio[8375]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 16:43:06 volumio volumio[8375]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 16:43:07 volumio go-librespot[8577]: time="2026-05-28T16:43:07+03:00" level=trace msg="sent dealer ping" May 28 16:43:07 volumio go-librespot[8577]: time="2026-05-28T16:43:07+03:00" level=trace msg="received dealer pong" May 28 16:43:10 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:10 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify May 28 16:43:10 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:10 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:10 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:10 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:12 volumio volumio[8375]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 16:43:12 volumio volumio[8375]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 16:43:14 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:14 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/playlists May 28 16:43:14 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:14 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 427. May 28 16:43:14 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:14 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:14 volumio upmpdcli[14037]: Could not open config: /tmp/upmpdcli.conf May 28 16:43:14 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:43:14 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:43:17 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:17 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/myalbums May 28 16:43:18 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:21 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:21 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/mytracks May 28 16:43:21 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:24 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:24 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/myartists May 28 16:43:25 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:26 volumio volumio[8375]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 16:43:29 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:29 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/mytoptracks May 28 16:43:29 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:29 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 428. May 28 16:43:29 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:29 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:29 volumio upmpdcli[14067]: Could not open config: /tmp/upmpdcli.conf May 28 16:43:29 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:43:29 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:43:30 volumio volumio[8375]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 16:43:30 volumio volumio[8375]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 16:43:37 volumio go-librespot[8577]: time="2026-05-28T16:43:37+03:00" level=trace msg="sent dealer ping" May 28 16:43:37 volumio go-librespot[8577]: time="2026-05-28T16:43:37+03:00" level=trace msg="received dealer pong" May 28 16:43:39 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:39 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks May 28 16:43:39 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:42 volumio volumio[8375]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 16:43:42 volumio volumio[8375]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 16:43:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 429. May 28 16:43:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:43:45 volumio upmpdcli[14082]: Could not open config: /tmp/upmpdcli.conf May 28 16:43:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:43:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:43:46 volumio volumio[8375]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 16:43:51 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:51 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXe9VRwc7kLEx May 28 16:43:51 volumio go-librespot[8577]: time="2026-05-28T16:43:51+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXe9VRwc7kLEx" May 28 16:43:51 volumio go-librespot[8577]: time="2026-05-28T16:43:51+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 28 16:43:52 volumio go-librespot[8577]: time="2026-05-28T16:43:52+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXe9VRwc7kLEx" May 28 16:43:52 volumio go-librespot[8577]: time="2026-05-28T16:43:52+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 28 16:43:53 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri May 28 16:43:53 volumio volumio[8375]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXe9VRwc7kLEx May 28 16:43:53 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6Q9L85FERsbSTVmNaZjhd4 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:7rnrHmjDOjeJTxmxHLSHIc May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:30zUFlAtXOEXikyIAhGhun May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3hqEXohUvBy76CKAxK2A9R May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2puBRG2ig5q8f0JcM1d9wD May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3LOXEAe5FMR1PRKiHAp9wt May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3RS5AmoANjZz0PXsOUjovL May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2wY3WPHlQCAhC9c5UZA8hl May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4Ny6sPSlZ6KGy9DBmLAnge May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4UJdWyF20InwrXuwJQtOio May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:5rfWp7g7v94ThG0ydHJlIq May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6dvpB2MjuzciJGECODPQdP May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6sdfSqn9Jz0hr2CYtOicQX May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:1Zw1dBNnFCyHWTRWTRk3wW May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4rX2CGUDJzZ2tHcYvkO2B3 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0WHpnRsewdrBExeDU90v2m May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3cTnyjyZpo93SfoO4RAA6v May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4URIs3gEDgakPiGq8bhe1G May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6q7CuZjtfJPs01qxugUbzn May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3FNGcipuHWekU1KvhdrAwH May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0W6HPXLquJqsIJgES1jzbI May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0Fc4uo72OdhOgIeoASzIT8 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:3yYIHG4gIaOJBsaDmG90GS May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0EKJGumFSnKoznDMkOMzNP May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6Q5NCz9pyJSSvmMgZpNEjz May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6V4gg3hHYIyYbcouvsqR3a May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2AlQniZwMjUWcOqtawNvxF May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6tfajS9NU9zqfOZdByVbV1 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:7A2hkIrbMR3643optl8Utm May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2TEHn9OHX4aq5uJOWkgbqu May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:1iTNgKcDKw0b2mRA1rMSJ5 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:7axy7PVFNdKylwB5JEuPV6 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4DUatRGBunA90nkbXWcM6i May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:4W5ed2BFQtDPFauzWgSgT3 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:5h0KGVgofdHjHBHgUmAGUu May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:57uiFyexstQyJ04VAAXa49 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:5e8JV9r2vKrbebUQMRpBCy May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0qE3a0JDePYhl0ml3Dy1oe May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:1598Nl52ChVtci9L16ghnG May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:49OvjhB5rxkF7KzfTEk9ri May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2vS4fNndj45xFMYRcAuLJU May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:5YqXe3HLxYmnKALdJy8NQm May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:2tq1vcOwuhgSuq1QEdLKjz May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:16pWLcflcWh4CNviOI9URy May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:7w7vv8KOKEC9VoyEaj49ww May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:7KhOj4f45JCwb72u0fzlbZ May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:5DPZMOE3VA3fHXASNhtPRT May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:0WtrVe6yIzLm6IFQRQ6Je5 May 28 16:43:53 volumio volumio[8375]: info: Preloading song: spotify:track:6lLyJtACSS8hb9ySXuBfCF May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:1w8p0vuFJ8PZBJka6jS6oA in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:6Q9L85FERsbSTVmNaZjhd4 in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6Q9L85FERsbSTVmNaZjhd4 May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:7rnrHmjDOjeJTxmxHLSHIc in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:7rnrHmjDOjeJTxmxHLSHIc May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:30zUFlAtXOEXikyIAhGhun in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:30zUFlAtXOEXikyIAhGhun May 28 16:43:53 volumio go-librespot[8577]: time="2026-05-28T16:43:53+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 28 16:43:53 volumio go-librespot[8577]: time="2026-05-28T16:43:53+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXe9VRwc7kLEx" May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:3hqEXohUvBy76CKAxK2A9R in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3hqEXohUvBy76CKAxK2A9R May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:2puBRG2ig5q8f0JcM1d9wD in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2puBRG2ig5q8f0JcM1d9wD May 28 16:43:53 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","service":"spop","name":"Eipes","artist":"Thodoris Ferris","album":"Eipes","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b273485ec8aa009391c4ae44e48d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:3LOXEAe5FMR1PRKiHAp9wt in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3LOXEAe5FMR1PRKiHAp9wt May 28 16:43:53 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Q9L85FERsbSTVmNaZjhd4","service":"spop","name":"Sousouro","artist":"Andromache","album":"Sousouro","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b273d453d0cff7381f443c9d72bf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:3RS5AmoANjZz0PXsOUjovL in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3RS5AmoANjZz0PXsOUjovL May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:2wY3WPHlQCAhC9c5UZA8hl in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2wY3WPHlQCAhC9c5UZA8hl May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:4Ny6sPSlZ6KGy9DBmLAnge in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4Ny6sPSlZ6KGy9DBmLAnge May 28 16:43:53 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7rnrHmjDOjeJTxmxHLSHIc","service":"spop","name":"Exairesi","artist":"Anna Vissi","album":"Exairesi","type":"song","duration":275,"albumart":"https://i.scdn.co/image/ab67616d0000b2730b4693ef4d1f7a2f62a0e982","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:53 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3hqEXohUvBy76CKAxK2A9R","service":"spop","name":"Exigiste Mou","artist":"Ria Ellinidou","album":"Exigiste Mou","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b2736a0ecf8b165af0028731c293","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:53 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30zUFlAtXOEXikyIAhGhun","service":"spop","name":"Ximeia","artist":"Katerina","album":"Ximeia","type":"song","duration":193,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7ec116080da4beaed6f51d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:4UJdWyF20InwrXuwJQtOio in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4UJdWyF20InwrXuwJQtOio May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:5rfWp7g7v94ThG0ydHJlIq in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:5rfWp7g7v94ThG0ydHJlIq May 28 16:43:53 volumio volumio[8375]: info: Exploding uri spotify:track:6dvpB2MjuzciJGECODPQdP in service spop May 28 16:43:53 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6dvpB2MjuzciJGECODPQdP May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:6sdfSqn9Jz0hr2CYtOicQX in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6sdfSqn9Jz0hr2CYtOicQX May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:1Zw1dBNnFCyHWTRWTRk3wW in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:1Zw1dBNnFCyHWTRWTRk3wW May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:4rX2CGUDJzZ2tHcYvkO2B3 in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4rX2CGUDJzZ2tHcYvkO2B3 May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:0WHpnRsewdrBExeDU90v2m in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0WHpnRsewdrBExeDU90v2m May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2puBRG2ig5q8f0JcM1d9wD","service":"spop","name":"Kala Na Pernate","artist":"Despina Vandi","album":"Kala Na Pernate","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2738126225b154943f8d0eaffaa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wY3WPHlQCAhC9c5UZA8hl","service":"spop","name":"Vrexei","artist":"Anastasia","album":"Vrexei","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273a75a638683c93910f3b92fbb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:3cTnyjyZpo93SfoO4RAA6v in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3cTnyjyZpo93SfoO4RAA6v May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3RS5AmoANjZz0PXsOUjovL","service":"spop","name":"Kale... Poios Einai Autos?","artist":"Andromache","album":"Kale... Poios Einai Autos?","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b2737d5c7b44eb06d71ce0ba837b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3LOXEAe5FMR1PRKiHAp9wt","service":"spop","name":"Deutera","artist":"Antonis Remos","album":"Deutera","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273b4a673f3544621790a03448b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Ny6sPSlZ6KGy9DBmLAnge","service":"spop","name":"Se Thelo Parafora","artist":"Nikos Makropoulos","album":"Se Thelo Parafora","type":"song","duration":201,"albumart":"https://i.scdn.co/image/ab67616d0000b273e0ce7c0f30bc872024a249ab","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:4URIs3gEDgakPiGq8bhe1G in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4URIs3gEDgakPiGq8bhe1G May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:6q7CuZjtfJPs01qxugUbzn in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6q7CuZjtfJPs01qxugUbzn May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:3FNGcipuHWekU1KvhdrAwH in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3FNGcipuHWekU1KvhdrAwH May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:0W6HPXLquJqsIJgES1jzbI in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0W6HPXLquJqsIJgES1jzbI May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:0Fc4uo72OdhOgIeoASzIT8 in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0Fc4uo72OdhOgIeoASzIT8 May 28 16:43:54 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4UJdWyF20InwrXuwJQtOio","service":"spop","name":"Plati","artist":"Thodoris Ferris","album":"Apo To Xthes Sto Simera (Part 2)","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b273b6c85b027d94a261d1bef74b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:3yYIHG4gIaOJBsaDmG90GS in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:3yYIHG4gIaOJBsaDmG90GS May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:0EKJGumFSnKoznDMkOMzNP in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0EKJGumFSnKoznDMkOMzNP May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:6Q5NCz9pyJSSvmMgZpNEjz in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6Q5NCz9pyJSSvmMgZpNEjz May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:6V4gg3hHYIyYbcouvsqR3a in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6V4gg3hHYIyYbcouvsqR3a May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:2AlQniZwMjUWcOqtawNvxF in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2AlQniZwMjUWcOqtawNvxF May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:6tfajS9NU9zqfOZdByVbV1 in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6tfajS9NU9zqfOZdByVbV1 May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:7A2hkIrbMR3643optl8Utm in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:7A2hkIrbMR3643optl8Utm May 28 16:43:54 volumio volumio[8375]: info: Exploding uri spotify:track:2TEHn9OHX4aq5uJOWkgbqu in service spop May 28 16:43:54 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2TEHn9OHX4aq5uJOWkgbqu May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:1iTNgKcDKw0b2mRA1rMSJ5 in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:1iTNgKcDKw0b2mRA1rMSJ5 May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:7axy7PVFNdKylwB5JEuPV6 in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:7axy7PVFNdKylwB5JEuPV6 May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:4DUatRGBunA90nkbXWcM6i in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4DUatRGBunA90nkbXWcM6i May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WHpnRsewdrBExeDU90v2m","service":"spop","name":"Kerasmena","artist":"Petros Iakovidis","album":"M' Akous","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b27301feac8f996b66dfd1847b23","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:4W5ed2BFQtDPFauzWgSgT3 in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:4W5ed2BFQtDPFauzWgSgT3 May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dvpB2MjuzciJGECODPQdP","service":"spop","name":"Epiptoseis","artist":"Giorgos Mazonakis","album":"Epiptoseis","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b27394933c079d507f27c65df0d0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5rfWp7g7v94ThG0ydHJlIq","service":"spop","name":"Pare Repo","artist":"Andromache","album":"Pare Repo","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6053133c30aaafb52cdd7c3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4rX2CGUDJzZ2tHcYvkO2B3","service":"spop","name":"Eroteftika Esena","artist":"Nikos Vertis","album":"Eroteftika Esena","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b273f7311a0c511bd9926affb907","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6sdfSqn9Jz0hr2CYtOicQX","service":"spop","name":"Kati Xereis","artist":"Ria Ellinidou","album":"Kati Xereis","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b2735520984b346ec18aa41c18ab","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:5h0KGVgofdHjHBHgUmAGUu in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:5h0KGVgofdHjHBHgUmAGUu May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:57uiFyexstQyJ04VAAXa49 in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:57uiFyexstQyJ04VAAXa49 May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:5e8JV9r2vKrbebUQMRpBCy in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:5e8JV9r2vKrbebUQMRpBCy May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:0qE3a0JDePYhl0ml3Dy1oe in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0qE3a0JDePYhl0ml3Dy1oe May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:1598Nl52ChVtci9L16ghnG in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:1598Nl52ChVtci9L16ghnG May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:49OvjhB5rxkF7KzfTEk9ri in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:49OvjhB5rxkF7KzfTEk9ri May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:2vS4fNndj45xFMYRcAuLJU in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2vS4fNndj45xFMYRcAuLJU May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3FNGcipuHWekU1KvhdrAwH","service":"spop","name":"Xorepse","artist":"Michalis Hatzigiannis","album":"Xorepse","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b2730b86aa37e89d382ab8befd2a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6q7CuZjtfJPs01qxugUbzn","service":"spop","name":"Skoteine Mou Erota","artist":"Thodoris Ferris","album":"Skoteine Mou Erota","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b273400e1eeb31f7de6660648e6c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:5YqXe3HLxYmnKALdJy8NQm in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:5YqXe3HLxYmnKALdJy8NQm May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4URIs3gEDgakPiGq8bhe1G","service":"spop","name":"Ores Mikres","artist":"Giorgos Mazonakis","album":"Agapo Simeni","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273e3c434fecb9b6a0a1ee7c1e3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cTnyjyZpo93SfoO4RAA6v","service":"spop","name":"Matia Mou","artist":"Ilias Vrettos","album":"Matia Mou","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b273bfcd28d728511fddcb586147","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:2tq1vcOwuhgSuq1QEdLKjz in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:2tq1vcOwuhgSuq1QEdLKjz May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:16pWLcflcWh4CNviOI9URy in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:16pWLcflcWh4CNviOI9URy May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:7w7vv8KOKEC9VoyEaj49ww in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:7w7vv8KOKEC9VoyEaj49ww May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:7KhOj4f45JCwb72u0fzlbZ in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:7KhOj4f45JCwb72u0fzlbZ May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:5DPZMOE3VA3fHXASNhtPRT in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:5DPZMOE3VA3fHXASNhtPRT May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:0WtrVe6yIzLm6IFQRQ6Je5 in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:0WtrVe6yIzLm6IFQRQ6Je5 May 28 16:43:55 volumio volumio[8375]: info: Exploding uri spotify:track:6lLyJtACSS8hb9ySXuBfCF in service spop May 28 16:43:55 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:track:6lLyJtACSS8hb9ySXuBfCF May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Zw1dBNnFCyHWTRWTRk3wW","service":"spop","name":"Siopi Sti Diapason","artist":"Ioulia Kallimani","album":"Siopi Sti Diapason","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273754f44f6190e8a6fb6497c35","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0W6HPXLquJqsIJgES1jzbI","service":"spop","name":"Athina Mou","artist":"Konstantinos Argiros","album":"Athina Mou","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b273d109d4c70bb5f891fd5a033d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Fc4uo72OdhOgIeoASzIT8","service":"spop","name":"Etsi Eimai","artist":"Nikos Apergis","album":"Etsi Eimai","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b2738d60cd78380ac7f61d785963","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Q5NCz9pyJSSvmMgZpNEjz","service":"spop","name":"Logia","artist":"Ivi Adamou","album":"Logia","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b273e89b5018bcdc4754b408fbc3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0EKJGumFSnKoznDMkOMzNP","service":"spop","name":"Lekes","artist":"Anna Vissi","album":"Lekes","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b2732b0718e59049ef26b303479f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:55 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3yYIHG4gIaOJBsaDmG90GS","service":"spop","name":"Parallili Agapi","artist":"Christina Salti","album":"Parallili Agapi","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273416dd3fe8e83868eae71f61c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6V4gg3hHYIyYbcouvsqR3a","service":"spop","name":"Tous Eipes Pos","artist":"Malu","album":"Ores Aixmis","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273ab90e805612737ad5198438c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2AlQniZwMjUWcOqtawNvxF","service":"spop","name":"To Viografiko","artist":"Nikos Oikonomopoulos","album":"To Viografiko","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b273186b23fe91f2258a3aa574e7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1iTNgKcDKw0b2mRA1rMSJ5","service":"spop","name":"Allo","artist":"Anastasia","album":"Allo","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2732f87d5bd27ce674767cc6003","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6tfajS9NU9zqfOZdByVbV1","service":"spop","name":"S' Agapao","artist":"Nikos Vertis","album":"S' Agapao","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b2739aabaa1acfa54941318db60e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7A2hkIrbMR3643optl8Utm","service":"spop","name":"Me 'Geia Sou","artist":"Katerina Lioliou","album":"Me 'Geia Sou","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b27321456e38397a216e61e19871","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2TEHn9OHX4aq5uJOWkgbqu","service":"spop","name":"Odos Tsimiski","artist":"Petros Iakovidis","album":"Odos Tsimiski","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b2731f5fd30d6c24c7f1b6f67b3e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7axy7PVFNdKylwB5JEuPV6","service":"spop","name":"Ekptotos Aggelos","artist":"Nikos Oikonomopoulos","album":"Ekptotos Aggelos","type":"song","duration":263,"albumart":"https://i.scdn.co/image/ab67616d0000b2739a847279adca1ff074d11805","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4W5ed2BFQtDPFauzWgSgT3","service":"spop","name":"Ela Ela","artist":"Lena Zevgara","album":"Ela Ela","type":"song","duration":115,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ab9f4ceac64804adaa94670","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5h0KGVgofdHjHBHgUmAGUu","service":"spop","name":"To Vale","artist":"Katerina Lioliou","album":"To Diko Mou DNA","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273d495cc79f95e440f45505a1b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4DUatRGBunA90nkbXWcM6i","service":"spop","name":"Fisixe Erotas Vorias","artist":"Ilias Vrettos","album":"Apo To Hthes Sto Simera","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273564cbda88a734fdae296cbc5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:56 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5e8JV9r2vKrbebUQMRpBCy","service":"spop","name":"7/7","artist":"Ioulia Kallimani","album":"7/7","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b273a926e32fa51935a6b33eb65c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:57uiFyexstQyJ04VAAXa49","service":"spop","name":"Floga","artist":"Anastasia","album":"Efhi","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf645a171b98953382457cdc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:49OvjhB5rxkF7KzfTEk9ri","service":"spop","name":"maZW Akoma (Ola Mou Ta Thelo)","artist":"Giorgos Mazonakis","album":"maZW Akoma (Ola Mou Ta Thelo)","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b2734804d840d10e35c56f65255f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1598Nl52ChVtci9L16ghnG","service":"spop","name":"Ela","artist":"Andromache","album":"Ela","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a28533c81f4f300b78ae873","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2vS4fNndj45xFMYRcAuLJU","service":"spop","name":"Perifanos Aetos","artist":"Konstantinos Argiros","album":"Perifanos Aetos","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273c300bac2f4007c27df0cc0e9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qE3a0JDePYhl0ml3Dy1oe","service":"spop","name":"De Ginetai","artist":"Giannis Ploutarhos","album":"De Ginetai","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b27397a0cc2625326835a266f46d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:57 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:58 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5YqXe3HLxYmnKALdJy8NQm","service":"spop","name":"Psixi Me Psixi","artist":"Giorgos Sabanis","album":"Psixi Me Psixi","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b273427c4000fad30667da3c806e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:58 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tq1vcOwuhgSuq1QEdLKjz","service":"spop","name":"Ela","artist":"Aspa","album":"Ela","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b2732b912173c6b3c3761f26a4e4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:58 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::ClearQueue May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::stPlaybackTimer May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::updateTrackBlock May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrackBlock May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::pushState May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioPushState May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::serviceStop May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::serviceStop May 28 16:43:58 volumio volumio[8375]: info: ControllerMpd::stop May 28 16:43:58 volumio volumio[8375]: verbose: ControllerMpd::sendMpdCommand stop May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::clearPlayQueue May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::addQueueItems May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::addQueueItems May 28 16:43:58 volumio volumio[8375]: info: Preload queue cleared May 28 16:43:58 volumio volumio[8375]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DXe9VRwc7kLEx May 28 16:43:58 volumio volumio[8375]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DXe9VRwc7kLEx in service spop May 28 16:43:58 volumio volumio[8375]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DXe9VRwc7kLEx May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.334+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" state=STATUS_STOPPED positionMs=0 volume=70 May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.336+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" id=http://streams.80s80s.de/dm/mp3-192/volumio title="Depeche Mode - Lilian" May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Volumio is not playing May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Clipped samples monitor stopped May 28 16:43:58 volumio volumio[8375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: sendMpdCommand stop took 114 milliseconds May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::servicePushState May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::pushState May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioPushState May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: verbose: STATE SERVICE {"status":"stop","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music123/v4/ae/cd/25/aecd2591-e9d6-6f81-a03c-74c9ce5c01c6/source/600x600bb.jpg","name":"Depeche Mode - Lilian","title":"Lilian","artist":"80s80s Depeche Mode","album":"","streaming":true,"disableUiControls":true,"duration":"267","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 28 16:43:58 volumio volumio[8375]: verbose: CURRENT POSITION 0 May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::syncState stateService stop May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::syncState currentStatus stop May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::pushState May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioPushState May 28 16:43:58 volumio volumio[8375]: info: No code May 28 16:43:58 volumio volumio[8375]: info: CoreStateMachine::pushState May 28 16:43:58 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:43:58 volumio volumio[8375]: info: CoreCommandRouter::volumioPushState May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.471+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" state=STATUS_STOPPED positionMs=0 volume=70 May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.472+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" id= title= May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.472+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" state=STATUS_STOPPED positionMs=0 volume=70 May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.473+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" state=STATUS_STOPPED positionMs=0 volume=70 May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.473+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" id= title= May 28 16:43:58 volumio volumio5-onboarding[1698]: time=2026-05-28T16:43:58.474+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.36:42904,00:00:00:00:00:00%15 @ 0x26bed50" id= title= May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Volumio is not playing May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Clipped samples monitor stopped May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Volumio is not playing May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Clipped samples monitor stopped May 28 16:43:58 volumio sudo[14118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Volumio is not playing May 28 16:43:58 volumio volumio[8375]: info: FusionDsp - Clipped samples monitor stopped May 28 16:43:58 volumio volumio[8375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 May 28 16:43:58 volumio volumio[8375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 May 28 16:43:58 volumio volumio[8375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14122]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio sudo[14124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14113]: pam_unix(sudo:session): session closed for user root May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio sudo[14130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio sudo[14135]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio volumio[8375]: info: PeppyMeterBasic ---peppymeterbasic status stop May 28 16:43:58 volumio sudo[14138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio sudo[14142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:58 volumio sudo[14145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 28 16:43:58 volumio sudo[14145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 16:43:59 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7w7vv8KOKEC9VoyEaj49ww","service":"spop","name":"Me Sighorite","artist":"Petros Iakovidis","album":"Me Sighorite","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b2736881aeceb21b7f99d63a4f31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7KhOj4f45JCwb72u0fzlbZ","service":"spop","name":"Pos Tin Eheis Dei","artist":"Giannis Ploutarhos","album":"Pos Tin Eheis Dei","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b273aed3fadff0d90eebd9ff62af","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:16pWLcflcWh4CNviOI9URy","service":"spop","name":"Anexartiti","artist":"Ioulia Kallimani","album":"Anexartiti","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b273eccd1259f108abe2e25768da","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DPZMOE3VA3fHXASNhtPRT","service":"spop","name":"Psahno Na Vro","artist":"Nikos Oikonomopoulos","album":"Psahno Na Vro","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b2733357aa92ff6a0986e25eb967","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WtrVe6yIzLm6IFQRQ6Je5","service":"spop","name":"Diamantia","artist":"Konstantinos Nazis","album":"Diamantia","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273ece06040314d7f02ebc4ef93","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio volumio[8375]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6lLyJtACSS8hb9ySXuBfCF","service":"spop","name":"Siga To Prama","artist":"Anastasia","album":"Siga To Prama","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b2738afde1f2a829ef669532a8fd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] May 28 16:43:59 volumio sudo[14118]: pam_unix(sudo:session): session closed for user root May 28 16:43:59 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14124]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14122]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:00 volumio sudo[14142]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::updateTrackBlock May 28 16:44:00 volumio volumio[8375]: info: CorePlayQueue::getTrackBlock May 28 16:44:00 volumio volumio[8375]: info: CoreCommandRouter::volumioPlay May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::play index 0 May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::play index undefined May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:00 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:00 volumio volumio[8375]: info: CoreStateMachine::startPlaybackTimer May 28 16:44:00 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:00 volumio volumio[8375]: info: [1779975840202] ControllerSpotify::clearAddPlayTrack May 28 16:44:00 volumio volumio[8375]: info: Sending Spotify command with payload to local API: /player/play May 28 16:44:00 volumio sudo[14138]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14135]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14132]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14126]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14130]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14115]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio sudo[14145]: pam_unix(sudo:session): session closed for user root May 28 16:44:00 volumio volumio[8375]: info: peppymeterbasic Daemon Stop May 28 16:44:00 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 430. May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=debug msg="resolved context of track" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:00 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=trace msg="emitting websocket event: will_play" May 28 16:44:00 volumio volumio[8375]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","play_origin":"go-librespot"}} May 28 16:44:00 volumio upmpdcli[14148]: Could not open config: /tmp/upmpdcli.conf May 28 16:44:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:44:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=debug msg="selected format OGG_VORBIS_320 (1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=debug msg="requested aes key for file 1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24, gid: 1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:00 volumio go-librespot[8577]: time="2026-05-28T16:44:00+03:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1w8p0vuFJ8PZBJka6jS6oA: failed retrieving audio key: failed retrieving aes key with code 1" May 28 16:44:00 volumio volumio[8375]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 28 16:44:05 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:05 volumio volumio[8375]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::ClearQueue May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::clearPlayQueue May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:05 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::addQueueItems May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::addQueueItems May 28 16:44:05 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:44:05 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::updateTrackBlock May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::getTrackBlock May 28 16:44:05 volumio volumio[8375]: info: CoreCommandRouter::volumioPlay May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::play index 0 May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::addQueueItems May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::addQueueItems May 28 16:44:05 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6Q9L85FERsbSTVmNaZjhd4 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6Q9L85FERsbSTVmNaZjhd4 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:7rnrHmjDOjeJTxmxHLSHIc May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:7rnrHmjDOjeJTxmxHLSHIc May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:30zUFlAtXOEXikyIAhGhun May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:30zUFlAtXOEXikyIAhGhun May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3hqEXohUvBy76CKAxK2A9R May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3hqEXohUvBy76CKAxK2A9R May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2puBRG2ig5q8f0JcM1d9wD May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2puBRG2ig5q8f0JcM1d9wD May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3LOXEAe5FMR1PRKiHAp9wt May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3LOXEAe5FMR1PRKiHAp9wt May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3RS5AmoANjZz0PXsOUjovL May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3RS5AmoANjZz0PXsOUjovL May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2wY3WPHlQCAhC9c5UZA8hl May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2wY3WPHlQCAhC9c5UZA8hl May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4Ny6sPSlZ6KGy9DBmLAnge May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4Ny6sPSlZ6KGy9DBmLAnge May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4UJdWyF20InwrXuwJQtOio May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4UJdWyF20InwrXuwJQtOio May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:5rfWp7g7v94ThG0ydHJlIq May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:5rfWp7g7v94ThG0ydHJlIq May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6dvpB2MjuzciJGECODPQdP May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6dvpB2MjuzciJGECODPQdP May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6sdfSqn9Jz0hr2CYtOicQX May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6sdfSqn9Jz0hr2CYtOicQX May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:1Zw1dBNnFCyHWTRWTRk3wW May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:1Zw1dBNnFCyHWTRWTRk3wW May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4rX2CGUDJzZ2tHcYvkO2B3 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4rX2CGUDJzZ2tHcYvkO2B3 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0WHpnRsewdrBExeDU90v2m May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0WHpnRsewdrBExeDU90v2m May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3cTnyjyZpo93SfoO4RAA6v May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3cTnyjyZpo93SfoO4RAA6v May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4URIs3gEDgakPiGq8bhe1G May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4URIs3gEDgakPiGq8bhe1G May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6q7CuZjtfJPs01qxugUbzn May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6q7CuZjtfJPs01qxugUbzn May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3FNGcipuHWekU1KvhdrAwH May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3FNGcipuHWekU1KvhdrAwH May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0W6HPXLquJqsIJgES1jzbI May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0W6HPXLquJqsIJgES1jzbI May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0Fc4uo72OdhOgIeoASzIT8 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0Fc4uo72OdhOgIeoASzIT8 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:3yYIHG4gIaOJBsaDmG90GS May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:3yYIHG4gIaOJBsaDmG90GS May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0EKJGumFSnKoznDMkOMzNP May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0EKJGumFSnKoznDMkOMzNP May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6Q5NCz9pyJSSvmMgZpNEjz May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6Q5NCz9pyJSSvmMgZpNEjz May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6V4gg3hHYIyYbcouvsqR3a May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6V4gg3hHYIyYbcouvsqR3a May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2AlQniZwMjUWcOqtawNvxF May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2AlQniZwMjUWcOqtawNvxF May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6tfajS9NU9zqfOZdByVbV1 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6tfajS9NU9zqfOZdByVbV1 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:7A2hkIrbMR3643optl8Utm May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:7A2hkIrbMR3643optl8Utm May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2TEHn9OHX4aq5uJOWkgbqu May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2TEHn9OHX4aq5uJOWkgbqu May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:1iTNgKcDKw0b2mRA1rMSJ5 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:1iTNgKcDKw0b2mRA1rMSJ5 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:7axy7PVFNdKylwB5JEuPV6 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:7axy7PVFNdKylwB5JEuPV6 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4DUatRGBunA90nkbXWcM6i May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4DUatRGBunA90nkbXWcM6i May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:4W5ed2BFQtDPFauzWgSgT3 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:4W5ed2BFQtDPFauzWgSgT3 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:5h0KGVgofdHjHBHgUmAGUu May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:5h0KGVgofdHjHBHgUmAGUu May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:57uiFyexstQyJ04VAAXa49 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:57uiFyexstQyJ04VAAXa49 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:5e8JV9r2vKrbebUQMRpBCy May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:5e8JV9r2vKrbebUQMRpBCy May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0qE3a0JDePYhl0ml3Dy1oe May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0qE3a0JDePYhl0ml3Dy1oe May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:1598Nl52ChVtci9L16ghnG May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:1598Nl52ChVtci9L16ghnG May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:49OvjhB5rxkF7KzfTEk9ri May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:49OvjhB5rxkF7KzfTEk9ri May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2vS4fNndj45xFMYRcAuLJU May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2vS4fNndj45xFMYRcAuLJU May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:5YqXe3HLxYmnKALdJy8NQm May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:5YqXe3HLxYmnKALdJy8NQm May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:2tq1vcOwuhgSuq1QEdLKjz May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:2tq1vcOwuhgSuq1QEdLKjz May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:16pWLcflcWh4CNviOI9URy May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:16pWLcflcWh4CNviOI9URy May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:7w7vv8KOKEC9VoyEaj49ww May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:7w7vv8KOKEC9VoyEaj49ww May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:7KhOj4f45JCwb72u0fzlbZ May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:7KhOj4f45JCwb72u0fzlbZ May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:5DPZMOE3VA3fHXASNhtPRT May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:5DPZMOE3VA3fHXASNhtPRT May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:0WtrVe6yIzLm6IFQRQ6Je5 May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:0WtrVe6yIzLm6IFQRQ6Je5 May 28 16:44:05 volumio volumio[8375]: info: Adding Item to queue: spotify:track:6lLyJtACSS8hb9ySXuBfCF May 28 16:44:05 volumio volumio[8375]: info: Using cached record of: spotify:track:6lLyJtACSS8hb9ySXuBfCF May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:05 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::play index undefined May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::updateTrackBlock May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::getTrackBlock May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:05 volumio volumio[8375]: info: CoreStateMachine::startPlaybackTimer May 28 16:44:05 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:05 volumio volumio[8375]: info: [1779975845162] ControllerSpotify::clearAddPlayTrack May 28 16:44:05 volumio volumio[8375]: info: Sending Spotify command with payload to local API: /player/play May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=debug msg="resolved context of track" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=trace msg="emitting websocket event: will_play" May 28 16:44:05 volumio volumio[8375]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","play_origin":"go-librespot"}} May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=debug msg="selected format OGG_VORBIS_320 (1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=debug msg="requested aes key for file 1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24, gid: 1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:05 volumio go-librespot[8577]: time="2026-05-28T16:44:05+03:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1w8p0vuFJ8PZBJka6jS6oA: failed retrieving audio key: failed retrieving aes key with code 1" May 28 16:44:05 volumio volumio[8375]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=trace msg="sent dealer ping" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=trace msg="received dealer pong" May 28 16:44:07 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:07 volumio volumio[8375]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::ClearQueue May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::clearPlayQueue May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:07 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::addQueueItems May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::addQueueItems May 28 16:44:07 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:07 volumio volumio[8375]: info: Adding Item to queue: spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:44:07 volumio volumio[8375]: info: Using cached record of: spotify:track:1w8p0vuFJ8PZBJka6jS6oA May 28 16:44:07 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::updateTrackBlock May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::getTrackBlock May 28 16:44:07 volumio volumio[8375]: info: CoreCommandRouter::volumioPlay May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::play index 0 May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::play index undefined May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:07 volumio volumio[8375]: info: CoreStateMachine::startPlaybackTimer May 28 16:44:07 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:07 volumio volumio[8375]: info: [1779975847526] ControllerSpotify::clearAddPlayTrack May 28 16:44:07 volumio volumio[8375]: info: Sending Spotify command with payload to local API: /player/play May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=debug msg="resolved context of track" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=trace msg="emitting websocket event: will_play" May 28 16:44:07 volumio volumio[8375]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","play_origin":"go-librespot"}} May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=debug msg="selected format OGG_VORBIS_320 (1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=debug msg="requested aes key for file 1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24, gid: 1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:07 volumio go-librespot[8577]: time="2026-05-28T16:44:07+03:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1w8p0vuFJ8PZBJka6jS6oA: failed retrieving audio key: failed retrieving aes key with code 1" May 28 16:44:07 volumio volumio[8375]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 28 16:44:10 volumio volumio[8375]: info: CoreCommandRouter::volumioPlay May 28 16:44:10 volumio volumio[8375]: info: CoreStateMachine::play index undefined May 28 16:44:10 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:10 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:10 volumio volumio[8375]: info: CoreStateMachine::startPlaybackTimer May 28 16:44:10 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:10 volumio volumio[8375]: info: [1779975850796] ControllerSpotify::clearAddPlayTrack May 28 16:44:10 volumio volumio[8375]: info: Sending Spotify command with payload to local API: /player/play May 28 16:44:10 volumio go-librespot[8577]: time="2026-05-28T16:44:10+03:00" level=debug msg="resolved context of track" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:10 volumio go-librespot[8577]: time="2026-05-28T16:44:10+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:10 volumio go-librespot[8577]: time="2026-05-28T16:44:10+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:10 volumio go-librespot[8577]: time="2026-05-28T16:44:10+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 28 16:44:10 volumio go-librespot[8577]: time="2026-05-28T16:44:10+03:00" level=trace msg="emitting websocket event: will_play" May 28 16:44:10 volumio volumio[8375]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","play_origin":"go-librespot"}} May 28 16:44:11 volumio go-librespot[8577]: time="2026-05-28T16:44:11+03:00" level=debug msg="selected format OGG_VORBIS_320 (1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:11 volumio go-librespot[8577]: time="2026-05-28T16:44:11+03:00" level=debug msg="requested aes key for file 1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24, gid: 1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:11 volumio go-librespot[8577]: time="2026-05-28T16:44:11+03:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1w8p0vuFJ8PZBJka6jS6oA: failed retrieving audio key: failed retrieving aes key with code 1" May 28 16:44:11 volumio volumio[8375]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 28 16:44:15 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 431. May 28 16:44:15 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:15 volumio upmpdcli[14164]: Could not open config: /tmp/upmpdcli.conf May 28 16:44:15 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:44:15 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:44:17 volumio volumio[8375]: info: CoreCommandRouter::volumioPlay May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::play index 0 May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::play index undefined May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:17 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:17 volumio volumio[8375]: info: CoreStateMachine::startPlaybackTimer May 28 16:44:17 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:17 volumio volumio[8375]: info: [1779975857396] ControllerSpotify::clearAddPlayTrack May 28 16:44:17 volumio volumio[8375]: info: Sending Spotify command with payload to local API: /player/play May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=debug msg="resolved context of track" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=trace msg="emitting websocket event: will_play" May 28 16:44:17 volumio volumio[8375]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","uri":"spotify:track:1w8p0vuFJ8PZBJka6jS6oA","play_origin":"go-librespot"}} May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=debug msg="selected format OGG_VORBIS_320 (1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24)" uri="spotify:track:1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=debug msg="requested aes key for file 1d7a09ecce3e64b2ac77c85b8e580c27f2f11e24, gid: 1w8p0vuFJ8PZBJka6jS6oA" May 28 16:44:17 volumio go-librespot[8577]: time="2026-05-28T16:44:17+03:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:1w8p0vuFJ8PZBJka6jS6oA: failed retrieving audio key: failed retrieving aes key with code 1" May 28 16:44:17 volumio volumio[8375]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error May 28 16:44:19 volumio volumio[8375]: info: CoreCommandRouter::volumioRemoveQueueItem May 28 16:44:19 volumio volumio[8375]: info: CoreStateMachine::removeQueueItem May 28 16:44:19 volumio volumio[8375]: info: CoreStateMachine::stop May 28 16:44:19 volumio volumio[8375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 16:44:19 volumio volumio[8375]: info: CorePlayQueue::removeQueueItem 0 May 28 16:44:19 volumio volumio[8375]: info: CorePlayQueue::saveQueue May 28 16:44:19 volumio volumio[8375]: info: CoreCommandRouter::volumioPushQueue May 28 16:44:23 volumio kernel: hwmon hwmon1: Voltage normalised May 28 16:44:25 volumio kernel: hwmon hwmon1: Undervoltage detected! May 28 16:44:31 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 432. May 28 16:44:31 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:31 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 28 16:44:31 volumio upmpdcli[14196]: Could not open config: /tmp/upmpdcli.conf May 28 16:44:31 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 28 16:44:31 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 28 16:44:34 volumio volumio[8375]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 28 16:44:34 volumio volumio[8375]: info: CURURI: music-library May 28 16:44:34 volumio volumio[8375]: error: Failed LSINFO: null May 28 16:44:34 volumio volumio[8375]: info: Preload queue cleared May 28 16:44:37 volumio go-librespot[8577]: time="2026-05-28T16:44:37+03:00" level=trace msg="sent dealer ping" May 28 16:44:37 volumio go-librespot[8577]: time="2026-05-28T16:44:37+03:00" level=trace msg="received dealer pong" May 28 16:44:42 volumio volumio[8375]: info: [1779975882466] [80s80s] Pushing the next song state Depeche Mode - Leave In Silence (Claro Intelecto The Last Time Remix) and getting next track. May 28 16:44:42 volumio volumio[8375]: info: CorePlayQueue::getTrack 0 May 28 16:44:42 volumio volumio[8375]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 16:44:42 volumio volumio[8375]: TypeError: Cannot set properties of undefined (setting 'name') May 28 16:44:42 volumio volumio[8375]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) May 28 16:44:42 volumio volumio[8375]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) May 28 16:44:42 volumio volumio[8375]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) May 28 16:44:42 volumio volumio[8375]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) May 28 16:44:42 volumio volumio[8375]: at process.processImmediate (node:internal/timers:478:21) May 28 16:44:42 volumio volumio[8375]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 16:44:43 volumio sudo[14225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 16:43' May 28 16:44:43 volumio sudo[14225]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"