Aug 27 21:58:02 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 27 21:58:02 volumio volumio[1254]: info: In handleBrowseUri, curUri=spotify:user:spotify:playlist:4XrB1EM5BM5flVwgTfUt2N Aug 27 21:58:03 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:03 volumio volumio[1254]: info: Preloading song: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:0hCB0YR03f6AmQaHbwWDe8 in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:6kjlvJLh2DBsSQtqVzFh8I in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:5HlLgmHL8qafp46jMtlxZJ in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:0d0Z9qFbTIgDSZtImluTAy in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6kjlvJLh2DBsSQtqVzFh8I","service":"spop","name":"Immigrant Song - 1990 Remaster","artist":"Led Zeppelin","album":"Led Zeppelin III (1994 Remaster)","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b2738862f99171ea71d0925ffc13","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:4lPJoKw24HizeIcDLqaWeE in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:6Vjk8MNXpQpi0F4BefdTyq in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:08mG3Y1vljYA6bvDt4Wqkj in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4lPJoKw24HizeIcDLqaWeE","service":"spop","name":"Trampled Under Foot - Remaster","artist":"Led Zeppelin","album":"Physical Graffiti (Deluxe Edition)","type":"song","duration":336,"albumart":"https://i.scdn.co/image/ab67616d0000b273765b0617b572bdd1dbdc7d8e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0hCB0YR03f6AmQaHbwWDe8","service":"spop","name":"Whole Lotta Love - 1990 Remaster","artist":"Led Zeppelin","album":"Led Zeppelin II (1994 Remaster)","type":"song","duration":333,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc4f17340773c6c3579fea0d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:2zYzyRzz6pRmhPzyfMEC8s in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0d0Z9qFbTIgDSZtImluTAy","service":"spop","name":"Houses of the Holy - Remaster","artist":"Led Zeppelin","album":"Physical Graffiti (Deluxe Edition)","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b273765b0617b572bdd1dbdc7d8e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6Vjk8MNXpQpi0F4BefdTyq","service":"spop","name":"Kashmir - Remaster","artist":"Led Zeppelin","album":"Physical Graffiti (Deluxe Edition)","type":"song","duration":517,"albumart":"https://i.scdn.co/image/ab67616d0000b273765b0617b572bdd1dbdc7d8e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:57bgtoPSgt236HzfBOd8kj in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:08mG3Y1vljYA6bvDt4Wqkj","service":"spop","name":"Back In Black","artist":"AC/DC","album":"Back In Black","type":"song","duration":256,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff191d7fbdb5a13eaf84132b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:7o2CTH4ctstm8TNelqjb51 in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2zYzyRzz6pRmhPzyfMEC8s","service":"spop","name":"Highway to Hell","artist":"AC/DC","album":"Highway to Hell","type":"song","duration":208,"albumart":"https://i.scdn.co/image/ab67616d0000b27343058ea096fa35ac33c43587","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:3YBZIN3rekqsKxbJc9FZko in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:57bgtoPSgt236HzfBOd8kj","service":"spop","name":"Thunderstruck","artist":"AC/DC","album":"The Razors Edge","type":"song","duration":292,"albumart":"https://i.scdn.co/image/ab67616d0000b2739a9b1cc067e4460da04adce2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:0bVtevEgtDIeRjCJbK3Lmv in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5HlLgmHL8qafp46jMtlxZJ","service":"spop","name":"Custard Pie - Remaster","artist":"Led Zeppelin","album":"Physical Graffiti (Deluxe Edition)","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b273765b0617b572bdd1dbdc7d8e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7o2CTH4ctstm8TNelqjb51","service":"spop","name":"Sweet Child O' Mine","artist":"Guns N' Roses","album":"Appetite For Destruction","type":"song","duration":354,"albumart":"https://i.scdn.co/image/ab67616d0000b27368384dd85fd5e95831252f60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:2aoo2jlRnM3A0NyLQqMN2f in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3YBZIN3rekqsKxbJc9FZko","service":"spop","name":"Paradise City","artist":"Guns N' Roses","album":"Appetite For Destruction","type":"song","duration":405,"albumart":"https://i.scdn.co/image/ab67616d0000b27368384dd85fd5e95831252f60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:03 volumio volumio[1254]: info: Exploding uri spotify:track:0NWPxcsf5vdjdiFUI8NgkP in service spop Aug 27 21:58:03 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:0wJoRiX5K5BxlqZTolB2LD in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bVtevEgtDIeRjCJbK3Lmv","service":"spop","name":"Welcome To The Jungle","artist":"Guns N' Roses","album":"Appetite For Destruction","type":"song","duration":272,"albumart":"https://i.scdn.co/image/ab67616d0000b27368384dd85fd5e95831252f60","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:2AxCeJ6PSsBYiTckM0HLY7 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0NWPxcsf5vdjdiFUI8NgkP","service":"spop","name":"Hey Joe","artist":"Jimi Hendrix","album":"Are You Experienced","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273c9adfbd773852e286faed040","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aoo2jlRnM3A0NyLQqMN2f","service":"spop","name":"All Along the Watchtower","artist":"Jimi Hendrix","album":"Electric Ladyland","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273522088789d49e216d9818292","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:2BcvvHttiZRvguFM4hR398 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:5X76oXHcR5uCXali0gOyX5 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0wJoRiX5K5BxlqZTolB2LD","service":"spop","name":"Purple Haze","artist":"Jimi Hendrix","album":"Are You Experienced","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273c9adfbd773852e286faed040","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2AxCeJ6PSsBYiTckM0HLY7","service":"spop","name":"Voodoo Child (Slight Return)","artist":"Jimi Hendrix","album":"Electric Ladyland","type":"song","duration":313,"albumart":"https://i.scdn.co/image/ab67616d0000b273522088789d49e216d9818292","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:3n1wTBzxeQmpLOC4Dp77b9 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2BcvvHttiZRvguFM4hR398","service":"spop","name":"Sunshine Of Your Love","artist":"Cream","album":"Disraeli Gears (Remastered)","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b2739f68634169de36a93f28c5ea","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:7IhkWF9zpiYKlU7CxHxYT9 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5X76oXHcR5uCXali0gOyX5","service":"spop","name":"White Room","artist":"Cream","album":"Wheels Of Fire (Remastered)","type":"song","duration":303,"albumart":"https://i.scdn.co/image/ab67616d0000b273a3fe725af4ddc7e2745b0603","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:63OFKbMaZSDZ4wtesuuq6f in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3n1wTBzxeQmpLOC4Dp77b9","service":"spop","name":"I Feel Free","artist":"Cream","album":"Fresh Cream (Remastered)","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273509ac44d6810bb009a3531ed","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:5SZ6zX4rOrEQferfFC2MfP in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7IhkWF9zpiYKlU7CxHxYT9","service":"spop","name":"All Right Now","artist":"Free","album":"Fire And Water (Deluxe Edition)","type":"song","duration":332,"albumart":"https://i.scdn.co/image/ab67616d0000b2735c07f5051c1df938e9d1f00c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:5MMnwYs0hIxkENRsbkWJ2G in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:63OFKbMaZSDZ4wtesuuq6f","service":"spop","name":"Born To Be Wild","artist":"Steppenwolf","album":"Steppenwolf","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273ec7e2c5c7ecd29301f1c4b93","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:3uMmllZo1AfoEnVT4ENCD3 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5SZ6zX4rOrEQferfFC2MfP","service":"spop","name":"Walk This Way","artist":"Aerosmith","album":"Toys In The Attic","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b2739662c6535fb4bf5767e50f32","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:5ERnPWGQc5QK7M7HW3rjC6 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:7ntdCok2CiwTmyiAh96N10 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:61NS6bXK93OwVlKb1ZTtBv in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3uMmllZo1AfoEnVT4ENCD3","service":"spop","name":"Highway Star - Remastered 2012","artist":"Deep Purple","album":"Machine Head (Remastered)","type":"song","duration":366,"albumart":"https://i.scdn.co/image/ab67616d0000b273a729c9c3dec04b99d889c66f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:51tuwnX8OPvQCXoBnjMkfF in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ntdCok2CiwTmyiAh96N10","service":"spop","name":"Since You Been Gone","artist":"Rainbow","album":"Down To Earth (Deluxe Edition)","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2734093b6fe7bd9eddf7fb02289","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ERnPWGQc5QK7M7HW3rjC6","service":"spop","name":"Paranoid","artist":"Black Sabbath","album":"Paranoid (Remastered)","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273d07e207ce654bec0813d028d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:1UBQ5GK8JaQjm5VbkBZY66 in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:70YvYr2hGlS01bKRIho1HM in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5MMnwYs0hIxkENRsbkWJ2G","service":"spop","name":"Smoke On The Water - Remastered 2012","artist":"Deep Purple","album":"Machine Head (Remastered)","type":"song","duration":340,"albumart":"https://i.scdn.co/image/ab67616d0000b273a729c9c3dec04b99d889c66f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:51tuwnX8OPvQCXoBnjMkfF","service":"spop","name":"Here I Go Again 87 - 2007 Remastered Version","artist":"Whitesnake","album":"1987","type":"song","duration":270,"albumart":"https://i.scdn.co/image/ab67616d0000b273445882baba2bcdb2df5dd224","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:0OBwxFLu6Yj61s2OagYbgY in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1UBQ5GK8JaQjm5VbkBZY66","service":"spop","name":"Sharp Dressed Man - 2008 Remaster","artist":"ZZ Top","album":"Eliminator","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b27328a00032ff3b86af3d7e0e54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:61NS6bXK93OwVlKb1ZTtBv","service":"spop","name":"This Flight Tonight","artist":"Nazareth","album":"Loud 'N' Proud","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273807e8c29ca755f341a4a5779","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:6zGDIDjfDkPyNxrEERO3XG in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:7t6CAWplijBj4sdl0q3z0e in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70YvYr2hGlS01bKRIho1HM","service":"spop","name":"La Grange - 2005 Remaster","artist":"ZZ Top","album":"Tres Hombres (Expanded 2006 Remaster)","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b2730fe2ec86a0ec561ef2e2aae1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0OBwxFLu6Yj61s2OagYbgY","service":"spop","name":"Gimme All Your Lovin'","artist":"ZZ Top","album":"Eliminator","type":"song","duration":241,"albumart":"https://i.scdn.co/image/ab67616d0000b27328a00032ff3b86af3d7e0e54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:04 volumio volumio[1254]: info: Exploding uri spotify:track:6s0NHplywwr1IjnQpUpWJk in service spop Aug 27 21:58:04 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6zGDIDjfDkPyNxrEERO3XG","service":"spop","name":"Tush - 2006 Remaster","artist":"ZZ Top","album":"Fandango! (Expanded 2006 Remaster)","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273b86736821a2d2f2da99b3963","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:4CJVkjo5WpmUAKp3R44LNb in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7t6CAWplijBj4sdl0q3z0e","service":"spop","name":"Legs - 2008 Remaster","artist":"ZZ Top","album":"Eliminator","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b27328a00032ff3b86af3d7e0e54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:4JNi40t7xR5bO3PWxRkiPN in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6s0NHplywwr1IjnQpUpWJk","service":"spop","name":"Bad To The Bone","artist":"George Thorogood & The Destroyers","album":"BAD TO THE BONE","type":"song","duration":292,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff4db4dae0252068fc3db08f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:0N7LKqYxUrg5UFb6Z4OSDF in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:371VkfwKiXJxgH5ZPoQNHD in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4CJVkjo5WpmUAKp3R44LNb","service":"spop","name":"Sweet Home Alabama","artist":"Lynyrd Skynyrd","album":"Second Helping","type":"song","duration":281,"albumart":"https://i.scdn.co/image/ab67616d0000b273bb1c0f2bc558a761eb2d7217","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:1dv3ePjze9tPq2pk8eWJdR in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0N7LKqYxUrg5UFb6Z4OSDF","service":"spop","name":"Hard To Handle","artist":"The Black Crowes","album":"Shake Your Money Maker","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27390d9cfc0869e6dd870ef6e31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:4sFbojhVXQv7dBC9PVCcRn in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:78Az0Z1vNJgqv9QSB0ULLV in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:371VkfwKiXJxgH5ZPoQNHD","service":"spop","name":"The Joker","artist":"Steve Miller Band","album":"The Joker","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b27375da4b79907852f0c5d0cd9e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:6ml6iL8HUdQKgtMaehAZc8 in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1dv3ePjze9tPq2pk8eWJdR","service":"spop","name":"Gimme Shelter","artist":"The Rolling Stones","album":"Let It Bleed","type":"song","duration":270,"albumart":"https://i.scdn.co/image/ab67616d0000b27373d92707b0e7da0c493f5b86","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:2KmEgiY8fQs0G6WNxtzQKr in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:6UdCTwbVAvNqbWyZKZiRWL in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4sFbojhVXQv7dBC9PVCcRn","service":"spop","name":"Sympathy For The Devil","artist":"The Rolling Stones","album":"Beggars Banquet","type":"song","duration":377,"albumart":"https://i.scdn.co/image/ab67616d0000b273c37907cebd91ee8a2ed1c1d1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:3x2bXiU0o4WbsPkawXlfDA in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4JNi40t7xR5bO3PWxRkiPN","service":"spop","name":"Free Bird","artist":"Lynyrd Skynyrd","album":"(Pronounced 'Leh-'Nérd 'Skin-'Nérd) [Expanded Edition]","type":"song","duration":558,"albumart":"https://i.scdn.co/image/ab67616d0000b2731f43eaa3c2a3188eb28dba82","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:78Az0Z1vNJgqv9QSB0ULLV","service":"spop","name":"Paint It Black","artist":"The Rolling Stones","album":"Singles 1965-1967","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273650fd478e285316014934a77","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:7xQMKG2omICY6LEAmJIfwk in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ml6iL8HUdQKgtMaehAZc8","service":"spop","name":"Start Me Up","artist":"The Rolling Stones","album":"Tattoo You (2009 Re-Mastered)","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b27361ce44658c2a0b453bde7d92","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6UdCTwbVAvNqbWyZKZiRWL","service":"spop","name":"My Generation - Original Mono Version","artist":"The Who","album":"My Generation (Remastered Mono Version)","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b2737822e765ff3459ca3c870e4d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:15wFxoOOHad7hGFifaU9ef in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2KmEgiY8fQs0G6WNxtzQKr","service":"spop","name":"Baba O'Riley","artist":"The Who","album":"Who's Next (Deluxe Edition)","type":"song","duration":300,"albumart":"https://i.scdn.co/image/ab67616d0000b27397922197e10d2a8dcb40eacf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3x2bXiU0o4WbsPkawXlfDA","service":"spop","name":"Who Are You","artist":"The Who","album":"Who Are You (Remastered)","type":"song","duration":378,"albumart":"https://i.scdn.co/image/ab67616d0000b273693812c5802c5e5d8fc987da","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:5nbNdS5SKdpiDZjCBh8W7b in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:5wycYLTeraGMJ8oPsNSjqS in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:0WNGPpmWqzPnk0psUhJ3SX in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:15wFxoOOHad7hGFifaU9ef","service":"spop","name":"Sunny Afternoon - Remastered","artist":"The Kinks","album":"This Time Tomorrow (Remastered)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b2735e34ac82cdac13f313a9a097","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5nbNdS5SKdpiDZjCBh8W7b","service":"spop","name":"Get It On","artist":"T. Rex","album":"Electric Warrior (Deluxe Edition)","type":"song","duration":262,"albumart":"https://i.scdn.co/image/ab67616d0000b2736b5c27aa80054957c984a931","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:672N8DGGTOLCOgWe0koX5g in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:3XC7Jd6SfrQYKZJ6inyRHK in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7xQMKG2omICY6LEAmJIfwk","service":"spop","name":"All Day And All Of The Night","artist":"The Kinks","album":"The Anthology 1964 - 1971","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b273be0c44aeb98f87f2eca966cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5wycYLTeraGMJ8oPsNSjqS","service":"spop","name":"Children of the Revolution","artist":"T. Rex","album":"Breakfast On Pluto","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273030497b175b71ee62a9e4e5f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:05 volumio volumio[1254]: info: Exploding uri spotify:track:0V1hBXTdYOWv3N2FVzG3OM in service spop Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:05 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0WNGPpmWqzPnk0psUhJ3SX","service":"spop","name":"All the Young Dudes","artist":"Mott The Hoople","album":"All The Young Dudes (Expanded Edition)","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b273d46e5889ace3c1457be450de","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:48t1ooCntbAzQW4HVGofkc in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:672N8DGGTOLCOgWe0koX5g","service":"spop","name":"I Wanna Be Your Dog","artist":"The Stooges","album":"The Stooges","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b27305f68ca95a483823a8f5a676","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:3aoDEt6zSuYQ47gzarlaVo in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0V1hBXTdYOWv3N2FVzG3OM","service":"spop","name":"Don't Let Me Be Misunderstood","artist":"The Animals","album":"A's B's & EP's","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b273f34da9e40d7895bc380c45c1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:0GTK6TesV108Jj5D3MHsYb in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:48t1ooCntbAzQW4HVGofkc","service":"spop","name":"We've Gotta Get out of This Place","artist":"The Animals","album":"The Complete Animals","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b273b49cefa2e0f92604950a0a63","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3aoDEt6zSuYQ47gzarlaVo","service":"spop","name":"Money - 2011 Remastered Version","artist":"Pink Floyd","album":"The Dark Side Of The Moon (2011 Remastered Version)","type":"song","duration":382,"albumart":"https://i.scdn.co/image/ab67616d0000b27305d4eec40a828850aa8cd2f7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4yrloqLBKS7Pi3z7g2HDga in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3fuXKiEzDCs1IBnLHQ5CL0","service":"spop","name":"Another Brick In The Wall, Pt. 2 - 2011 Remastered Version","artist":"Pink Floyd","album":"The Wall [2011 - Remaster] (2011 Remastered Version)","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b273e17969dceed6ced1c671d323","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4wYq5wugZDzQiMZQYG4wVB in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:1QEEqeFIZktqIpPI4jSVSF in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GTK6TesV108Jj5D3MHsYb","service":"spop","name":"Owner of a Lonely Heart","artist":"Yes","album":"90125 (Deluxe Version)","type":"song","duration":268,"albumart":"https://i.scdn.co/image/ab67616d0000b2732527c93491d39fe7e95b95ac","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4bHsxqR3GMrXTxEPLuK5ue in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4yrloqLBKS7Pi3z7g2HDga","service":"spop","name":"Somebody to Love","artist":"Jefferson Airplane","album":"Best Of","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273bbeb3427487bea7fa87c1061","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:06 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:06 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:06 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:06 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:06 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::play index 0 Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:06 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:3XC7Jd6SfrQYKZJ6inyRHK in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:06 volumio volumio[1254]: info: Using cached record of: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4wYq5wugZDzQiMZQYG4wVB in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:1QEEqeFIZktqIpPI4jSVSF in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4bHsxqR3GMrXTxEPLuK5ue in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:1zOaxkbS1kAY1gIi0g8sOm in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:2gz5g0gQBb2THFDdLD4U8e in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:5bC4y9Zhn6ng0MOzACCHkc in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:6nek1Nin9q48AVZcWs9e9D in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:06 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:06 volumio volumio[1254]: info: Exploding uri spotify:track:4jhsuQCUCJKA5f0bXb6XdJ in service spop Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:06 volumio volumio[1254]: info: [1787864286488] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:06 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QEEqeFIZktqIpPI4jSVSF","service":"spop","name":"More Than a Feeling","artist":"Boston","album":"Boston","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c1fadcc997a65384f34d694","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4wYq5wugZDzQiMZQYG4wVB","service":"spop","name":"Whole Lotta Rosie","artist":"AC/DC","album":"Let There Be Rock","type":"song","duration":324,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff9064748ad3e70d76a55c66","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1zOaxkbS1kAY1gIi0g8sOm","service":"spop","name":"It's My Life","artist":"Bon Jovi","album":"Crush","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b27305e593aaa47659429ba3804e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XC7Jd6SfrQYKZJ6inyRHK","service":"spop","name":"House of the Rising Sun","artist":"The Animals","album":"Most of the Animals","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273590e89b4c65a02050d8086b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4wYq5wugZDzQiMZQYG4wVB","service":"spop","name":"Whole Lotta Rosie","artist":"AC/DC","album":"Let There Be Rock","type":"song","duration":324,"albumart":"https://i.scdn.co/image/ab67616d0000b273ff9064748ad3e70d76a55c66","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bHsxqR3GMrXTxEPLuK5ue","service":"spop","name":"Don't Stop Believin'","artist":"Journey","album":"Escape (Bonus Track Version)","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5653f9038e42efad2f8a266","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6nek1Nin9q48AVZcWs9e9D","service":"spop","name":"Paradise","artist":"Coldplay","album":"Mylo Xyloto","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b273de0cd11d7b31c3bd1fd5983d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2gz5g0gQBb2THFDdLD4U8e","service":"spop","name":"Dude (Looks Like A Lady)","artist":"Aerosmith","album":"Foxboro Stadium, Mass. September 6th, 1993 (Remastered, Live On Broadcasting)","type":"song","duration":311,"albumart":"https://i.scdn.co/image/ab67616d0000b27349bced000ab6c15f8f47b271","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4bHsxqR3GMrXTxEPLuK5ue","service":"spop","name":"Don't Stop Believin'","artist":"Journey","album":"Escape (Bonus Track Version)","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5653f9038e42efad2f8a266","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XC7Jd6SfrQYKZJ6inyRHK","service":"spop","name":"House of the Rising Sun","artist":"The Animals","album":"Most of the Animals","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273590e89b4c65a02050d8086b9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt","service":"spop","name":"Centerfold","artist":"The J. Geils Band","album":"Freeze Frame","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b27338b2429ef948e6ca8d3ab599","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QEEqeFIZktqIpPI4jSVSF","service":"spop","name":"More Than a Feeling","artist":"Boston","album":"Boston","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c1fadcc997a65384f34d694","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj","service":"spop","name":"Walk This Way","artist":"Aerosmith","album":"Foxboro Stadium, Mass. September 6th, 1993 (Remastered, Live On Broadcasting)","type":"song","duration":286,"albumart":"https://i.scdn.co/image/ab67616d0000b27349bced000ab6c15f8f47b271","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5bC4y9Zhn6ng0MOzACCHkc","service":"spop","name":"For Your Love","artist":"The Yardbirds","album":"The Yardbirds Story - Pt. 3 - 1965/66 - Big Hits & America Calling","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b27395670c43925b9ce8a437c9b4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4jhsuQCUCJKA5f0bXb6XdJ","service":"spop","name":"It's My Life - 1997 Remaster","artist":"Talk Talk","album":"It's My Life","type":"song","duration":232,"albumart":"https://i.scdn.co/image/ab67616d0000b273ed7b5f97f354c216f80d61da","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Aug 27 21:58:06 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:06 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:06 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:23 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:23 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:23 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::play index 0 Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:23 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:23 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:23 volumio volumio[1254]: info: Using cached record of: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:23 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:23 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:23 volumio volumio[1254]: info: [1787864303910] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:23 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:24 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:24 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:24 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:24 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:24 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:24 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::play index 0 Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:24 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6kjlvJLh2DBsSQtqVzFh8I Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5HlLgmHL8qafp46jMtlxZJ Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0d0Z9qFbTIgDSZtImluTAy Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4lPJoKw24HizeIcDLqaWeE Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6Vjk8MNXpQpi0F4BefdTyq Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:08mG3Y1vljYA6bvDt4Wqkj Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2zYzyRzz6pRmhPzyfMEC8s Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:57bgtoPSgt236HzfBOd8kj Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:7o2CTH4ctstm8TNelqjb51 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3YBZIN3rekqsKxbJc9FZko Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0bVtevEgtDIeRjCJbK3Lmv Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2aoo2jlRnM3A0NyLQqMN2f Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0NWPxcsf5vdjdiFUI8NgkP Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0wJoRiX5K5BxlqZTolB2LD Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2AxCeJ6PSsBYiTckM0HLY7 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2BcvvHttiZRvguFM4hR398 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5X76oXHcR5uCXali0gOyX5 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3n1wTBzxeQmpLOC4Dp77b9 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:7IhkWF9zpiYKlU7CxHxYT9 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:63OFKbMaZSDZ4wtesuuq6f Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5SZ6zX4rOrEQferfFC2MfP Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5MMnwYs0hIxkENRsbkWJ2G Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3uMmllZo1AfoEnVT4ENCD3 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5ERnPWGQc5QK7M7HW3rjC6 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:7ntdCok2CiwTmyiAh96N10 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:61NS6bXK93OwVlKb1ZTtBv Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:51tuwnX8OPvQCXoBnjMkfF Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:1UBQ5GK8JaQjm5VbkBZY66 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:70YvYr2hGlS01bKRIho1HM Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0OBwxFLu6Yj61s2OagYbgY Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6zGDIDjfDkPyNxrEERO3XG Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:7t6CAWplijBj4sdl0q3z0e Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6s0NHplywwr1IjnQpUpWJk Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4CJVkjo5WpmUAKp3R44LNb Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4JNi40t7xR5bO3PWxRkiPN Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0N7LKqYxUrg5UFb6Z4OSDF Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:371VkfwKiXJxgH5ZPoQNHD Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:1dv3ePjze9tPq2pk8eWJdR Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4sFbojhVXQv7dBC9PVCcRn Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:78Az0Z1vNJgqv9QSB0ULLV Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6ml6iL8HUdQKgtMaehAZc8 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2KmEgiY8fQs0G6WNxtzQKr Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6UdCTwbVAvNqbWyZKZiRWL Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3x2bXiU0o4WbsPkawXlfDA Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:7xQMKG2omICY6LEAmJIfwk Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:15wFxoOOHad7hGFifaU9ef Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5nbNdS5SKdpiDZjCBh8W7b Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5wycYLTeraGMJ8oPsNSjqS Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0WNGPpmWqzPnk0psUhJ3SX Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:672N8DGGTOLCOgWe0koX5g Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3XC7Jd6SfrQYKZJ6inyRHK Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0V1hBXTdYOWv3N2FVzG3OM Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:48t1ooCntbAzQW4HVGofkc Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3aoDEt6zSuYQ47gzarlaVo Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:3fuXKiEzDCs1IBnLHQ5CL0 Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0GTK6TesV108Jj5D3MHsYb Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4yrloqLBKS7Pi3z7g2HDga Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4wYq5wugZDzQiMZQYG4wVB Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:1QEEqeFIZktqIpPI4jSVSF Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4bHsxqR3GMrXTxEPLuK5ue Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:1zOaxkbS1kAY1gIi0g8sOm Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:2gz5g0gQBb2THFDdLD4U8e Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:0A9L7Yq9vmoMKaw7ZCNJsj Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:1ynmMEK1fkyiZ6Z6F3ThEt Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:5bC4y9Zhn6ng0MOzACCHkc Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:6nek1Nin9q48AVZcWs9e9D Aug 27 21:58:24 volumio volumio[1254]: info: Adding Item to queue: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:24 volumio volumio[1254]: info: Using cached record of: spotify:track:4jhsuQCUCJKA5f0bXb6XdJ Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:24 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:24 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:24 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:24 volumio volumio[1254]: info: [1787864304715] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:24 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:25 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 21:58:28 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:28 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:28 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:28 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:28 volumio volumio[1254]: info: Adding Item to queue: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:28 volumio volumio[1254]: info: Using cached record of: spotify:track:0hCB0YR03f6AmQaHbwWDe8 Aug 27 21:58:28 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:28 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::play index 0 Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:28 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:28 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:28 volumio volumio[1254]: info: [1787864308664] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:28 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:29 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 21:58:29 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 21:58:37 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:37 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:37 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:37 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:37 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:37 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:37 volumio volumio[1254]: info: [1787864317594] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:37 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:39 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:39 volumio volumio[1254]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 21:58:39 volumio volumio[1254]: info: CoreStateMachine::ClearQueue Aug 27 21:58:39 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:39 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:39 volumio volumio[1254]: info: CorePlayQueue::clearPlayQueue Aug 27 21:58:39 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:39 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:39 volumio volumio[1254]: info: CoreStateMachine::addQueueItems Aug 27 21:58:39 volumio volumio[1254]: info: CorePlayQueue::addQueueItems Aug 27 21:58:39 volumio volumio[1254]: info: Preload queue cleared Aug 27 21:58:39 volumio volumio[1254]: info: Adding Item to queue: spotify:user:spotify:playlist:4XrB1EM5BM5flVwgTfUt2N Aug 27 21:58:39 volumio volumio[1254]: info: Exploding uri spotify:user:spotify:playlist:4XrB1EM5BM5flVwgTfUt2N in service spop Aug 27 21:58:39 volumio volumio[1254]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:4XrB1EM5BM5flVwgTfUt2N Aug 27 21:58:40 volumio volumio[1254]: info: CoreCommandRouter::volumioPushQueue Aug 27 21:58:40 volumio volumio[1254]: info: CorePlayQueue::saveQueue Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::updateTrackBlock Aug 27 21:58:40 volumio volumio[1254]: info: CorePlayQueue::getTrackBlock Aug 27 21:58:40 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::play index 0 Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::stop Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:40 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:40 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:40 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:40 volumio volumio[1254]: info: [1787864320397] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:40 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:41 volumio volumio[1254]: info: CoreCommandRouter::volumioPlay Aug 27 21:58:41 volumio volumio[1254]: info: CoreStateMachine::play index undefined Aug 27 21:58:41 volumio volumio[1254]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 21:58:41 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:41 volumio volumio[1254]: info: CoreStateMachine::startPlaybackTimer Aug 27 21:58:41 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:41 volumio volumio[1254]: info: [1787864321043] ControllerSpotify::clearAddPlayTrack Aug 27 21:58:41 volumio volumio[1254]: info: Sending Spotify command with payload to local API: /player/play Aug 27 21:58:45 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:58:45 volumio volumio[1254]: info: Received Get System Info Aug 27 21:58:45 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:58:45 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:58:45 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:58:45 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:58:45 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:45 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:58:46 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 21:58:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:58:48 volumio volumio[1254]: info: Received Get System Info Aug 27 21:58:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:58:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:58:48 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:58:48 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:58:48 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:48 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:58:49 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:58:49 volumio volumio[1254]: info: Received Get System Info Aug 27 21:58:49 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:58:49 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:58:49 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:58:49 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:58:49 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:58:49 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:58:50 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:58:50 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 21:58:58 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:59:05 volumio volumio[1254]: info: Received OAUTH Data Aug 27 21:59:05 volumio volumio[1254]: info: Executing Spotify Oauth Login Aug 27 21:59:05 volumio volumio[1254]: info: Saving Spotify Refresh Token Aug 27 21:59:05 volumio volumio[1254]: info: New Spotify access tokenBQC1ox7k0y... Aug 27 21:59:05 volumio volumio[1254]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 27 21:59:06 volumio sudo[8325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:59:06 volumio sudo[8325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:06 volumio sudo[8327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:59:06 volumio sudo[8327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:06 volumio sudo[8325]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:06 volumio sudo[8327]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:06 volumio volumio[1254]: verbose: New Socket.io Connection to 192.168.1.83 from 192.168.1.208 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Aug 27 21:59:06 volumio volumio[1254]: SPOTIFY: User informations: {"account_id":"wEVuXsj6qA","country":"GB","display_name":"andymcdonald1962","email":"andrew_j_mcdonald@btinternet.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/andymcdonald1962"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/andymcdonald1962","id":"andymcdonald1962","images":[],"product":"premium","type":"user","uri":"spotify:user:andymcdonald1962"} Aug 27 21:59:06 volumio volumio[1254]: info: Creating Spotify config file Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 21:59:06 volumio volumio[1254]: info: Spotify config file written Aug 27 21:59:06 volumio sudo[8346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:59:06 volumio sudo[8346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:06 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 21:59:06 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Aug 27 21:59:06 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Aug 27 21:59:06 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:06 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:06 volumio volumio[1254]: info: Connection to go-librespot Websocket closed Aug 27 21:59:06 volumio go-librespot[8348]: go-librespot daemon starting... Aug 27 21:59:06 volumio sudo[8346]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="app state loaded" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:06 volumio volumio[1254]: info: New Spotify access tokenBQBoGJT0Bi... Aug 27 21:59:06 volumio volumio[1254]: info: Spotify credentials grant success - running version from March 24, 2019 Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=info msg="zeroconf server listening on port 38001" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="obtained new client token: AAHglbS9H5/x4MPZP0O0OXhkQA/41hJBJrYBnmrR+vXnkYfRNwdJ0lSdg/15H/GxqkbxhMpVaeTynz48ErxThQh/iAfNjN/oJWrdyaUdagp31oiyp+pI/z67fBiWTehmpXOKV4hxOPY2ywu5OnIgDqsWj07QXvQ15IRbACqngJOy8sctoUol82pveCecb8e8LEtdhF5APAev5BTv8NlOMP8kWFoEQUuMLC2p9oQbg0EpW2MPXsYw3e8w6A==" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:06 volumio volumio[1254]: SPOTIFY: User informations: {"account_id":"wEVuXsj6qA","country":"GB","display_name":"andymcdonald1962","email":"andrew_j_mcdonald@btinternet.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/andymcdonald1962"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/andymcdonald1962","id":"andymcdonald1962","images":[],"product":"premium","type":"user","uri":"spotify:user:andymcdonald1962"} Aug 27 21:59:06 volumio volumio[1254]: info: Spotify Successfully logged in Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 21:59:06 volumio volumio[1254]: info: [1787864346800] CoreMusicLibrary::Adding element Spotify Aug 27 21:59:06 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 21:59:06 volumio volumio[1254]: Cannot find translation for source Spotify Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=debug msg="completed challenge" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:06 volumio go-librespot[8349]: time="2026-08-27T21:59:06+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:09 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:09 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:09 volumio volumio[1254]: info: go-librespot daemon successfully initialized Aug 27 21:59:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Aug 27 21:59:10 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:10 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:10 volumio go-librespot[8360]: go-librespot daemon starting... Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="app state loaded" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=info msg="zeroconf server listening on port 44589" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="obtained new client token: AAFMA1ivb0RZTCXJ35fKDs0y9ScmSuIaqDkrOT9qaftz3oxiPceTW2xMVjkrh7YBkzn2FZdK4iETL17ANihAbj+LEXmF1PHPhQPSFQ4LlUZLBoQng+EALtICIKggrr8kb/8WM/PXsCHZ2TPbfW3ca0C7aV4cHIg7cTCiUifAQ5GFRaqCAUXZjxrLZbsxtpLK5/UncHPKUImfsNRmxBE3a380JpXz28YLF0oLl8DeayOxqGmSJFexhtpUXw==" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=debug msg="completed challenge" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:10 volumio go-librespot[8361]: time="2026-08-27T21:59:10+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:12 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:12 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:12 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:12 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Aug 27 21:59:13 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:13 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:13 volumio go-librespot[8370]: go-librespot daemon starting... Aug 27 21:59:13 volumio go-librespot[8371]: time="2026-08-27T21:59:13+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:13 volumio go-librespot[8371]: time="2026-08-27T21:59:13+01:00" level=debug msg="app state loaded" Aug 27 21:59:13 volumio go-librespot[8371]: time="2026-08-27T21:59:13+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:13 volumio go-librespot[8371]: time="2026-08-27T21:59:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=info msg="zeroconf server listening on port 45909" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="obtained new client token: AAE/NAms6J+325nR00dYJbLeeQc2tbbbYHdrnOG9mFGG2/6ON1pUy1Mfpi3gT4lmiUHjH6bcv7gYiWje3SZq5X0TcGvayast7FBq6G3qyg1ItpQzaaq1WXIyED4kRHkIA3F0kqKZp5HuWcTKI/t8X9RTjvyEkWZAdEZ+zrf9EwmcZ36H9punJ9FRI5Xgb5O25/3jf45FnmG9swjF0nzqRF7ZJoq8MWtjSZ6rMrLpLaCHzaU1O8V8yrI=" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=debug msg="completed challenge" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:14 volumio go-librespot[8371]: time="2026-08-27T21:59:14+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 21:59:14 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:14 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:14 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:14 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:14 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:15 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:15 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Aug 27 21:59:17 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:17 volumio go-librespot[8394]: go-librespot daemon starting... Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="app state loaded" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=info msg="zeroconf server listening on port 36071" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="obtained new client token: AAEvJk1lB5BuJLPi9t08i5Z+sCc9LXgUK37rdp3HVwenqxcJBONKoX8OR7YUW9Db+A3n2Ujgi1Hmxlc7bPgYcUdF9oP8T4CSTxTo6YR+DJMJKJF6by48dXzNMTZZ1F4YTjJnrOjjGHyNrsLVAoR8kfEBTIMEamD75jN/5CrobmxLfPA6va21b8rHoEgO/Ppyg9OsGFZx/YnCPchC70CppRYZU26uBca8ipLY/E/HgVIZVKhrehI2J9N0Vg==" Aug 27 21:59:17 volumio go-librespot[8395]: time="2026-08-27T21:59:17+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:18 volumio go-librespot[8395]: time="2026-08-27T21:59:18+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:18 volumio go-librespot[8395]: time="2026-08-27T21:59:18+01:00" level=debug msg="completed challenge" Aug 27 21:59:18 volumio go-librespot[8395]: time="2026-08-27T21:59:18+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:18 volumio go-librespot[8395]: time="2026-08-27T21:59:18+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:18 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:18 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:19 volumio volumio[1254]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 27 21:59:19 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 27 21:59:19 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 27 21:59:19 volumio sudo[8409]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 27 21:59:19 volumio sudo[8409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8414]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:59:19 volumio sudo[8414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8414]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:59:19 volumio sudo[8409]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:59:19 volumio sudo[8420]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:59:19 volumio sudo[8420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8420]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio sudo[8432]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 21:59:19 volumio sudo[8426]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 21:59:19 volumio sudo[8426]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8426]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio sudo[8432]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio sudo[8434]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 21:59:19 volumio sudo[8434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:19 volumio sudo[8434]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:19 volumio sudo[8438]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:59:19 volumio sudo[8438]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:20 volumio sudo[8438]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:20 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:20.875+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.208:49829,00:00:00:00:00:00%01 @ 0x1c00a20" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:59:20 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:20 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:20 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:59:20 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:59:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Aug 27 21:59:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:21 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:21 volumio go-librespot[8443]: go-librespot daemon starting... Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="app state loaded" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:21 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:21 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=info msg="zeroconf server listening on port 37119" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="obtained new client token: AAGH6stSNnkSKMuHjGiQAvmvBaYDWKz5Y+NXK39SI6vmvtTVfIze7v/TmUFL5uvr4GI/K7U7mjFumPmi/V7B8Hvtfb07QhOFuF8RhVE5majAEzCjf1/gmX8Mu0vBdAwwEgzyj6kwLggjlfTMfoeAxEey5WBKQbBweDhL8Uqz/8Ltvj6bYUtzQ0ikUZEi91Al7ksV/V23UJm5dt58SVHzGVVoubw/xCdMEja/1esFa6BcwPZKaCiriuK7UA==" Aug 27 21:59:21 volumio go-librespot[8444]: time="2026-08-27T21:59:21+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:21 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:21.870+01:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:59:22 volumio go-librespot[8444]: time="2026-08-27T21:59:22+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:22 volumio go-librespot[8444]: time="2026-08-27T21:59:22+01:00" level=debug msg="completed challenge" Aug 27 21:59:22 volumio go-librespot[8444]: time="2026-08-27T21:59:22+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:22 volumio go-librespot[8444]: time="2026-08-27T21:59:22+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:59:22 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:59:22 volumio sudo[8454]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:59:22 volumio sudo[8454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:23 volumio sudo[8454]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:23 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:23.659+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.208:49829,00:00:00:00:00:00%01 @ 0x1c00a20" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:59:23 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:23 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:23 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:59:23 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 21:59:24 volumio volumio[1254]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 27 21:59:24 volumio volumio[1254]: info: Received Get System Version Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 21:59:24 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:24 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:24 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:24 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:24 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:59:24 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:59:24 volumio sudo[8457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:59:24 volumio sudo[8457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:24 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:24.655+01:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:59:25 volumio sudo[8457]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Aug 27 21:59:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:25 volumio go-librespot[8459]: go-librespot daemon starting... Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="app state loaded" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:25 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:25.673+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.208:49829,00:00:00:00:00:00%01 @ 0x1c00a20" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:59:25 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:25 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:25 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:59:25 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=info msg="zeroconf server listening on port 42961" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:25 volumio go-librespot[8460]: time="2026-08-27T21:59:25+01:00" level=debug msg="obtained new client token: AAE3WS0XcQyO8NJ5Io0X994ru3ipkhE8QtVGv9jO+c3asCI2QOhOSKiB54Sy9vce8IjXGqI4sWDCSZ9vf/J6LVGYB3JNa3binAvu/6Ohx2XQpBQpN3dX1gatGW13R099TV0Ys4Fq6YZH2dAv4sOpgrIEepFfwNz4yqjo6+4xDUs3KShKJ5GqcT0Ma7mIis6q0qjekEWe7AkqKd2uDInvpzDMqOL+FSVyVC0CRY9RP5xoGt0L4v8gclDdWg==" Aug 27 21:59:26 volumio go-librespot[8460]: time="2026-08-27T21:59:26+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:26 volumio go-librespot[8460]: time="2026-08-27T21:59:26+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:26 volumio go-librespot[8460]: time="2026-08-27T21:59:26+01:00" level=debug msg="completed challenge" Aug 27 21:59:26 volumio go-librespot[8460]: time="2026-08-27T21:59:26+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:26 volumio go-librespot[8460]: time="2026-08-27T21:59:26+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 27 21:59:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 27 21:59:26 volumio sudo[8484]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 27 21:59:26 volumio sudo[8484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:26 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:26.674+01:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:59:27 volumio sudo[8484]: pam_unix(sudo:session): session closed for user root Aug 27 21:59:27 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:27 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:27 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:27.678+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.208:49829,00:00:00:00:00:00%01 @ 0x1c00a20" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 27 21:59:27 volumio volumio[1254]: info: Received Get System Info Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 21:59:27 volumio volumio[1254]: info: Discovery: Getting this device information Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 27 21:59:27 volumio volumio[1254]: info: CorePlayQueue::getTrack 0 Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 21:59:27 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 21:59:28 volumio volumio5-onboarding[1470]: time=2026-08-27T21:59:28.655+01:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 21:59:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Aug 27 21:59:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:29 volumio go-librespot[8486]: go-librespot daemon starting... Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="app state loaded" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=info msg="zeroconf server listening on port 40935" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="obtained new client token: AAEiphNLwGmEZwha2xzpjet/qz4aFwAhG2nvBUdCgqtRFFV79CeikZmMan4X9d/Adkq0s7eS5zoj+PymG/K27YgDPBuz/0wT3u1lCYs5kTxtiDfnfpu8ZBgu7Ssfp4l5iVixHyaukEb4c9NSKGg8Yc67dweYdnuW9P5BoxEHfMhETcODq5qx+CJxMLcbgaTDBfmNInK2aW9GOHAFYFDJg5QvPZStTYIxAZyzxwf0X2uNzo1VB5zNkpWwWg==" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=debug msg="completed challenge" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:29 volumio go-librespot[8487]: time="2026-08-27T21:59:29+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:30 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:30 volumio volumio[1254]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Aug 27 21:59:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:33 volumio go-librespot[8496]: go-librespot daemon starting... Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="app state loaded" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:33 volumio volumio[1254]: info: Initializing connection to go-librespot Websocket Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=info msg="zeroconf server listening on port 39723" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="new websocket client" Aug 27 21:59:33 volumio volumio[1254]: info: Connection to go-librespot Websocket established Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="obtained new client token: AAF2Uf41arHmDUW7Dj/Te06+4K0IkzpIA1TNma10RTfU6rNcznhyAGiVN34EN3mRzqxxY13jm+DOZEXn3IjJ2+QrIv8CqJgfBhuFHkDgl0tekRkW3u6Xt67HoD4Hp/qAA4QllPUitnoaCXW7lxewnVIaH5chW6ix88PjmmbxELirIYvikljFwZ0GslC/zjYABvo1DceUPAwX5OJUxBs/I4/yNz84heV0MsrYy8XYMbR/9s+YV3UmFeUzQg==" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=debug msg="completed challenge" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=info msg="authenticated AP" username="an************62" Aug 27 21:59:33 volumio go-librespot[8497]: time="2026-08-27T21:59:33+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating with login5: failed authenticating with login5: INVALID_CREDENTIALS" Aug 27 21:59:33 volumio volumio[1254]: info: Connection to go-librespot Websocket closed Aug 27 21:59:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 27 21:59:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 27 21:59:36 volumio volumio[1254]: info: Getting Spotify volume Aug 27 21:59:36 volumio volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:59:36 volumio volumio[1254]: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 27 21:59:36 volumio volumio[1254]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Aug 27 21:59:36 volumio volumio[1254]: errno: -111, Aug 27 21:59:36 volumio volumio[1254]: code: 'ECONNREFUSED', Aug 27 21:59:36 volumio volumio[1254]: syscall: 'connect', Aug 27 21:59:36 volumio volumio[1254]: address: '127.0.0.1', Aug 27 21:59:36 volumio volumio[1254]: port: 9879, Aug 27 21:59:36 volumio volumio[1254]: response: undefined Aug 27 21:59:36 volumio volumio[1254]: } Aug 27 21:59:36 volumio volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 21:59:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Aug 27 21:59:36 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:36 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 27 21:59:36 volumio go-librespot[8533]: go-librespot daemon starting... Aug 27 21:59:36 volumio go-librespot[8534]: time="2026-08-27T21:59:36+01:00" level=info msg="running go-librespot 0.7.1" Aug 27 21:59:36 volumio go-librespot[8534]: time="2026-08-27T21:59:36+01:00" level=debug msg="app state loaded" Aug 27 21:59:36 volumio go-librespot[8534]: time="2026-08-27T21:59:36+01:00" level=debug msg="stored credentials not found" Aug 27 21:59:36 volumio go-librespot[8534]: time="2026-08-27T21:59:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=info msg="zeroconf server listening on port 34435" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 27 21:59:37 volumio sudo[8545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 21:58' Aug 27 21:59:37 volumio sudo[8545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="obtained new client token: AAFF7COiSzshpe2EbMK5CpRvBEhrSDlrp8ee3+ZenbsMZzestqXRrXe/Z/FZa/b18Kj7nQt6LL3htUqMHetmE88bF1+dWjL11s6j8u9sWK6UBBWirmHA0uWVlwhRGGx38L9UH+Kufi9JevBzlfUqduTsNGt68/os8KGt66CaLJNCgEtcDQgpjhjnYYTtYSFA1weuSpF8l7tyXazLYjp9mjNtI3VNeGwS79yJWW+pVmLPfW9vWUJeVLY=" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="completed keyexchange" Aug 27 21:59:37 volumio go-librespot[8534]: time="2026-08-27T21:59:37+01:00" level=debug msg="completed challenge" 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"