-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Wed 2025-09-10 21:48:37 EDT. -- Sep 10 21:47:00 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:00-04:00" level=trace msg="sent dealer ping" Sep 10 21:47:00 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:00-04:00" level=trace msg="received dealer pong" Sep 10 21:47:01 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:01-04:00" level=trace msg="received accesspoint ping" Sep 10 21:47:01 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:01-04:00" level=trace msg="received accesspoint pong ack" Sep 10 21:47:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 21:47:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 21:47:04 volumiopizeroa volumio[833]: info: Discovery: Getting this device information Sep 10 21:47:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioGetState Sep 10 21:47:04 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 1 Sep 10 21:47:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 21:47:09 volumiopizeroa nmbd[652]: [2025/09/10 21:47:09.820420, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 10 21:47:09 volumiopizeroa nmbd[652]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.119 for name WORKGROUP<1d>. Sep 10 21:47:09 volumiopizeroa nmbd[652]: This response was from IP 192.168.3.113, reporting an IP address of 192.168.3.113. Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 4 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:15 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:15 volumiopizeroa volumio[833]: info: [1757555235800] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:15 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:15 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:15-04:00" level=debug msg="resolved context of track" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:15 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:15-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:15 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:15-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:16 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:16-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:16 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:16-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:16 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5ti5SeraA9Ci7OtbLhEkz2","play_origin":"go-librespot"}} Sep 10 21:47:16 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:16-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:16 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:16-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:5ti5SeraA9Ci7OtbLhEkz2: no supported formats" Sep 10 21:47:16 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 4 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:17 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:17 volumiopizeroa volumio[833]: info: [1757555237699] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:17 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:17 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:17-04:00" level=debug msg="resolved context of track" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:17 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:17-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:17 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:17-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:17 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:17-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:17 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:17-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:17 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5ti5SeraA9Ci7OtbLhEkz2","play_origin":"go-librespot"}} Sep 10 21:47:18 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:18-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:18 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:18-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:5ti5SeraA9Ci7OtbLhEkz2: no supported formats" Sep 10 21:47:18 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2dPTRjTrpjGUIuhPyfP9B5 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3I5c9NOMgfabYL8xYvPLBf Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2ySYhf6EXewS4moUSSseX8 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0toMErMc2remz5OwZTwCNF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ti5SeraA9Ci7OtbLhEkz2 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 4 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4KU1AZfXvTPhrr9ptpVVPp Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1iEr3OkczMbk557RgIMtxm Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1p8Co3ZVnAwtKm0hUc0yVN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1Wsr7giAA9iTER7UZV1qhU Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sv1F2rgS5sNVECTrNKIma Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4SyA5hOVnp08RKo6eQAOir Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3qAhVTOAubSP4sjMpzFoRp Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6kO5VVgGb9jEZM9cKXoOqc Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2Ercj0hlr2NVNLOsbWECP5 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0cnFQVdwqvAkq3MinhdCf0 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0L5FTei1MmePVB9ECKOMwd Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:63Koh0VeDgrhZ5p0MPwIAd Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:16bhGT1p47EVS4zB3qcAFw Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:79Scr6iQiMOJ8k2BJHGT2A Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7eHWHvVUYNPmHGpQSIRBOu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1ih2064mxsKnMDfWN4jXnR Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3gL4IrzOINdUqACQzThhQU Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6zvJJLQdJuM81BDkBJDAh9 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4r5M7k2tWM6PuESO4AgMh4 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxm2gT6406WT9QY1mz4tu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2BNGH110uJH1wxcw55DiIH Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6aHrmABARkuroPv9CNDmhS Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0xQguLwFZSGfGnpfaUXMNF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7jRMxu5JhxnaQ9iqSHs3hH Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2jAN90bCRP7J0XSkuhVRN9 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SlOROUWa6OQe77oID5nix Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:44rVj5qSoGdTcgFhWCWJbO Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:41xLMZFjxXWXKVMmAOfL8I Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2KK16ZS9B4LdovfUE3ThuZ Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0yEb7b0L50krjgYGuOCCbf Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0Vz4RuHCtrrNzIuchyIq4W Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45ftg8gaAKdxVKL3MHJfRF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02LWWHtoUThrWIR9zYTiEN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:75lkSQB1nXFkwqwHrrz8HN Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5IyoWlrfrAgx43gBZ9rM3K Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3B1SoWeLyahoXawK11nqQR Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5Sz2U70uxRIn6ukKxklNTg Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7tCZOAxyB7a41TGe2liLHu Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0usDbYIMx50UYcwuXdpmSj Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3ViF0NjUDhmPVtPuHKOdnE Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3RHWlpdHQq2wQrY1mjU8dT Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1NESFObit3X7XKydaeDweP Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7BujMCC8IhoBorzOgBnj88 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3r4Ct3eaOaiRmaqu9tlzJF Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6r4e2Yjlt4opFysenbx08o Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:20 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:20 volumiopizeroa volumio[833]: info: [1757555240374] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:20 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=debug msg="resolved context of track" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:20 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:5ti5SeraA9Ci7OtbLhEkz2","play_origin":"go-librespot"}} Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:5ti5SeraA9Ci7OtbLhEkz2" Sep 10 21:47:20 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:20-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:5ti5SeraA9Ci7OtbLhEkz2: no supported formats" Sep 10 21:47:20 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:30 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:30-04:00" level=trace msg="sent dealer ping" Sep 10 21:47:30 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:30-04:00" level=trace msg="received dealer pong" Sep 10 21:47:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 21:47:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 21:47:34 volumiopizeroa volumio[833]: info: Discovery: Getting this device information Sep 10 21:47:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioGetState Sep 10 21:47:34 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 4 Sep 10 21:47:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Sep 10 21:47:37 volumiopizeroa volumio[833]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXcBWIGoYBM5M Sep 10 21:47:37 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:37-04:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXcBWIGoYBM5M" Sep 10 21:47:37 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:37-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Sep 10 21:47:37 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:37 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:37 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:37 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DXcBWIGoYBM5M Sep 10 21:47:37 volumiopizeroa volumio[833]: info: Exploding uri spotify:playlist:37i9dQZF1DXcBWIGoYBM5M in service spop Sep 10 21:47:37 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DXcBWIGoYBM5M Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Preloading song: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:42VUCXerQ5qTr4Qp6PhKo4 in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3NFs3XUduzBfvc5Bx1gmzh in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6qqrTXSdwiJaq8SO0X2lSe in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1mEQbSq0PCNjyL6oMgHKFQ in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3cZajhyr8LmtPfHZ9296tj in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 0 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:38 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: [1757555258748] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:4WFgvKVfEhb3IUAFGrutTR in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:2SsY5k7UWFqgye3PUMG3Oq in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qqrTXSdwiJaq8SO0X2lSe","service":"spop","name":"Ordinary","artist":"Alex Warren","album":"Ordinary","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273fa2bcf3df669bde08b2e7d36","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3NFs3XUduzBfvc5Bx1gmzh","service":"spop","name":"The Dead Dance","artist":"Lady Gaga","album":"The Dead Dance","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8a389abdf9cb2d03b85ff54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:38-04:00" level=debug msg="resolved context of track" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:38 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:38-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:38 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:38-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0FTmksd2dxiE5e3rWyJXs6 in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1mEQbSq0PCNjyL6oMgHKFQ","service":"spop","name":"LOVE SONG","artist":"Justin Bieber","album":"SWAG II","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273239851044d4fffe6fe8a7215","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1IfGOnXoMBkiKC9xljlSfa in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4WFgvKVfEhb3IUAFGrutTR","service":"spop","name":"Love Me Not","artist":"Ravyn Lenae","album":"Bird's Eye","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273ef985ba96e76a9574cc68a30","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2SsY5k7UWFqgye3PUMG3Oq","service":"spop","name":"The Subway","artist":"Chappell Roan","album":"The Subway","type":"song","duration":252,"albumart":"https://i.scdn.co/image/ab67616d0000b2730b96f8824100aab118792442","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","service":"spop","name":"Tears","artist":"Sabrina Carpenter","album":"Man’s Best Friend","type":"song","duration":160,"albumart":"https://i.scdn.co/image/ab67616d0000b27345e9957f1f728941041d3210","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:2yWlGEgEfPot0lv3OAjuG3 in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:38 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:42UBPzRMh5yyz0EDPr6fr1 in service spop Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:38 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:38-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:38 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:38-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","play_origin":"go-librespot"}} Sep 10 21:47:38 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0FTmksd2dxiE5e3rWyJXs6","service":"spop","name":"back to friends","artist":"sombr","album":"back to friends","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b2739d24f74c1e2d8a12b1e591ec","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:5H1sKFMzDeMtXwND3V6hRY in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:39 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:39-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:39 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:39-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:42VUCXerQ5qTr4Qp6PhKo4: no supported formats" Sep 10 21:47:39 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:5ITV0zqzjOYfFWpW0xBmRa in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1IfGOnXoMBkiKC9xljlSfa","service":"spop","name":"Jealous Type","artist":"Doja Cat","album":"Jealous Type","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b273d347cb974ff024a545c5cd54","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1CPZ5BxNNd0n0nF4Orb9JS in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yWlGEgEfPot0lv3OAjuG3","service":"spop","name":"Just Keep Watching (From F1® The Movie)","artist":"Tate McRae","album":"Just Keep Watching (From F1® The Movie)","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b27396e4cfdd4c3aa8088685b262","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5H1sKFMzDeMtXwND3V6hRY","service":"spop","name":"JUMP","artist":"BLACKPINK","album":"JUMP","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b2736224d1236b0e0a0e1586efbb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:42UBPzRMh5yyz0EDPr6fr1","service":"spop","name":"Manchild","artist":"Sabrina Carpenter","album":"Manchild","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273062c6573009fdebd43de443b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:5BZsQlgw21vDOAjoqkNgKb in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3cZajhyr8LmtPfHZ9296tj","service":"spop","name":"No Broke Boys","artist":"Disco Lines","album":"No Broke Boys","type":"song","duration":163,"albumart":"https://i.scdn.co/image/ab67616d0000b2735dcede7ece7b2cb72cee4eee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1qbmS6ep2hbBRaEZFpn7BX in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5ITV0zqzjOYfFWpW0xBmRa","service":"spop","name":"Show Me Love","artist":"WizTheMc","album":"Show Me Love","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273f67307349b14986af73d4fa0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:221eHga9f9Ne4f8D7WVgPT in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","service":"spop","name":"Golden","artist":"HUNTR/X","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2734dcb6c5df15cf74596ab25a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:04emojnbYkrRmv5qtJcgVP in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:4AajxCEwGEsmHmT4H1TwjY in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BZsQlgw21vDOAjoqkNgKb","service":"spop","name":"DAISIES","artist":"Justin Bieber","album":"SWAG","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273172de562840d588bfdda27f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qbmS6ep2hbBRaEZFpn7BX","service":"spop","name":"Man I Need","artist":"Olivia Dean","album":"Man I Need","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273092c5e1cf9c3d58916791987","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:221eHga9f9Ne4f8D7WVgPT","service":"spop","name":"Mystical Magical","artist":"Benson Boone","album":"Mystical Magical","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273df98c5b533f9feac6fa81061","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3SKH53SPQbEnZR4cJPVaz2 in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:2plbrEY59IikOBgBGLjaoe in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:04emojnbYkrRmv5qtJcgVP","service":"spop","name":"What I Want (feat. Tate McRae)","artist":"Morgan Wallen","album":"I’m The Problem","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b27335ea219ce47813b5e2dc3745","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4AajxCEwGEsmHmT4H1TwjY","service":"spop","name":"undressed","artist":"sombr","album":"undressed","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273dad39d4906f76b7c176effb1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6dOtVTDdiauQNBQEDOtlAB in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:7ne4VBA60CxGM75vw0EYad in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SKH53SPQbEnZR4cJPVaz2","service":"spop","name":"Messy","artist":"Lola Young","album":"Messy","type":"song","duration":284,"albumart":"https://i.scdn.co/image/ab67616d0000b273a0cb974834e04f46b63b99a8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0bxPRWprUVpQK0UFcddkrA in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1k2pQc5i348DCHwbn5KTdc in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2plbrEY59IikOBgBGLjaoe","service":"spop","name":"Die With A Smile","artist":"Lady Gaga","album":"Die With A Smile","type":"song","duration":251,"albumart":"https://i.scdn.co/image/ab67616d0000b27382ea2e9e1858aa012c57cd45","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dOtVTDdiauQNBQEDOtlAB","service":"spop","name":"BIRDS OF A FEATHER","artist":"Billie Eilish","album":"HIT ME HARD AND SOFT","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b27371d62ea7ea8a5be92d3c1f62","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ne4VBA60CxGM75vw0EYad","service":"spop","name":"That’s So True","artist":"Gracie Abrams","album":"The Secret of Us (Deluxe)","type":"song","duration":166,"albumart":"https://i.scdn.co/image/ab67616d0000b2731dac3694b3289cd903cb3acf","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3nHuKdFJZm78CoeBnDcFKe in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6dWilYAxP2aJbTTt0UsatK in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bxPRWprUVpQK0UFcddkrA","service":"spop","name":"Folded","artist":"Kehlani","album":"Folded","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b273c23c6110d6061ba7e2311141","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:45J4avUb9Ni0bnETYaYFVJ in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:1xOqGUkyxGQRdCvGpvWKmL in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3nHuKdFJZm78CoeBnDcFKe","service":"spop","name":"Bad Dreams","artist":"Teddy Swims","album":"Bad Dreams","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b273de4b0388cb6e5267132a07bc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1k2pQc5i348DCHwbn5KTdc","service":"spop","name":"Pink Pony Club","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b27396fa88fb1789be437d5cb4b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6wHpLMmp98aHcV8L1JFrj8 in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6dWilYAxP2aJbTTt0UsatK","service":"spop","name":"Lover Girl","artist":"Laufey","album":"Lover Girl","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b273be1e41eda793059fb9129bff","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:45J4avUb9Ni0bnETYaYFVJ","service":"spop","name":"luther (with sza)","artist":"Kendrick Lamar","album":"GNX","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273d9985092cd88bffd97653b58","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:39 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6xV7Be6XEvkSnighmh2Tzj in service spop Sep 10 21:47:39 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0FIDCNYYjNvPVimz5icugS in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xOqGUkyxGQRdCvGpvWKmL","service":"spop","name":"Gabriela","artist":"KATSEYE","album":"Gabriela","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b273f8d4d00ffe09373efb13ce29","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:4Q0qVhFQa7j6jRKzo3HDmP in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6wHpLMmp98aHcV8L1JFrj8","service":"spop","name":"Eternity","artist":"Alex Warren","album":"You'll Be Alright, Kid","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0a2d7a2d8406a4a72a92d5c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6uiQ0DqbXTBc8tl0AUKZkR in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6xV7Be6XEvkSnighmh2Tzj","service":"spop","name":"Sugar On My Tongue","artist":"Tyler, The Creator","album":"DON'T TAP THE GLASS","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b273979047951fa8b15df9f9e984","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0FIDCNYYjNvPVimz5icugS","service":"spop","name":"Timeless (feat Playboi Carti)","artist":"The Weeknd","album":"Hurry Up Tomorrow","type":"song","duration":256,"albumart":"https://i.scdn.co/image/ab67616d0000b273982320da137d0de34410df61","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3hBzKgqQKkChH9hyb3MhmT in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:5FMyXeZ0reYloRTiCkPprT in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Q0qVhFQa7j6jRKzo3HDmP","service":"spop","name":"Sapphire","artist":"Ed Sheeran","album":"Sapphire","type":"song","duration":179,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fbb60d6a7e03ccb940a518e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6uiQ0DqbXTBc8tl0AUKZkR","service":"spop","name":"TU VAS SIN (fav)","artist":"Rels B","album":"afroLOVA 25'","type":"song","duration":110,"albumart":"https://i.scdn.co/image/ab67616d0000b2730e409fad9527196e73e423ba","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0InIeZW4P6VO7dUGRM4AKH in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0QCIpQV3twfqo9kh0t8Zza in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3hBzKgqQKkChH9hyb3MhmT","service":"spop","name":"I Think I Like You Better When You’re Gone","artist":"Reneé Rapp","album":"BITE ME","type":"song","duration":143,"albumart":"https://i.scdn.co/image/ab67616d0000b2731f22a0d14fbba8dd80b186a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5FMyXeZ0reYloRTiCkPprT","service":"spop","name":"Which One (feat. Central Cee)","artist":"Drake","album":"Which One","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b2738028a7b8c9fce086d4a47ba7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3QaPy1KgI7nu9FJEQUgn6h in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0InIeZW4P6VO7dUGRM4AKH","service":"spop","name":"Sienna","artist":"The Marías","album":"Submarine","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b2734d070fdf58fad8c54c5beb85","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:02BcXEH1zJYbXSabPtNlKf in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:2zOmS55knKWSgScYPTNmGQ in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0QCIpQV3twfqo9kh0t8Zza","service":"spop","name":"Shake It To The Max (FLY) - Remix","artist":"MOLIY","album":"Shake It To The Max (FLY) (Remix)","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b2730069ee4e37f913fb1653b389","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0fK7ie6XwGxQTIkpFoWkd1 in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3QaPy1KgI7nu9FJEQUgn6h","service":"spop","name":"WILDFLOWER","artist":"Billie Eilish","album":"HIT ME HARD AND SOFT","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b27371d62ea7ea8a5be92d3c1f62","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:78nx0HDJIFD5xDq2L5420Z in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:21IYMdzTrzSe191Cy5eMap in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:02BcXEH1zJYbXSabPtNlKf","service":"spop","name":"Lose My Mind (feat. Doja Cat) [From F1® The Movie]","artist":"Don Toliver","album":"Lose My Mind (feat. Doja Cat) [From F1® The Movie]","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273bed9c7b9e33025a81d4d02e4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0GRc3eGTg8HBdWLRGYgqIc in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2zOmS55knKWSgScYPTNmGQ","service":"spop","name":"Sports car","artist":"Tate McRae","album":"Sports car","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b2739064126e1dfd1414f8de9315","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:78nx0HDJIFD5xDq2L5420Z","service":"spop","name":"Blessings","artist":"Calvin Harris","album":"Blessings","type":"song","duration":219,"albumart":"https://i.scdn.co/image/ab67616d0000b2734ba976bec2d9619b6a88aad2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:5vNRhkKd0yEAg8suGBpjeY in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:0nafF9MxcXJBQWv3BTKtdF in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6iOndD4OFo7GkaDypWQIou in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:21IYMdzTrzSe191Cy5eMap","service":"spop","name":"Sailor Song","artist":"Gigi Perez","album":"At The Beach, In Every Life","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b27349c26270052e1671add569c1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0fK7ie6XwGxQTIkpFoWkd1","service":"spop","name":"like JENNIE","artist":"JENNIE","album":"Ruby","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a43918ea90bf1e44b7bdcfd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:3SAga35lAPYdjj3qyfEsCF in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5vNRhkKd0yEAg8suGBpjeY","service":"spop","name":"APT.","artist":"ROSÉ","album":"APT.","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27336032cb4acd9df050bc2e197","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: info: Exploding uri spotify:track:6eLQXa6uk2EW8drsiKbABZ in service spop Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: EXPLODING URI:spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0GRc3eGTg8HBdWLRGYgqIc","service":"spop","name":"Azizam","artist":"Ed Sheeran","album":"Azizam","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b273d29a003b6e8d4c1c47bd4da4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:40 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0nafF9MxcXJBQWv3BTKtdF","service":"spop","name":"Verano Rosa","artist":"KAROL G","album":"Tropicoqueta","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b2734018a6ba07312ec74af169e6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:41 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SAga35lAPYdjj3qyfEsCF","service":"spop","name":"Feel It (From “Invincible”)","artist":"d4vd","album":"Feel It (From “Invincible”)","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273a9da992c2ef398245027d654","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:41 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6eLQXa6uk2EW8drsiKbABZ","service":"spop","name":"TU SANCHO","artist":"Fuerza Regida","album":"111XPANTIA","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b2732a2f9ea25cf65b5fb744b604","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:41 volumiopizeroa volumio[833]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6iOndD4OFo7GkaDypWQIou","service":"spop","name":"La Plena - W Sound 05","artist":"W Sound","album":"La Plena (W Sound 05)","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b2734740100d84f3667f1eae6870","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 0 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:42 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:42 volumiopizeroa volumio[833]: info: [1757555262704] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:42 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:42 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:42-04:00" level=debug msg="resolved context of track" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:42 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:42-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:42 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:42-04:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:42 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:42-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:42 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:42-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:42 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","play_origin":"go-librespot"}} Sep 10 21:47:43 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:43-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:43 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:43-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:42VUCXerQ5qTr4Qp6PhKo4: no supported formats" Sep 10 21:47:43 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 0 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:50 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:50 volumiopizeroa volumio[833]: info: [1757555270312] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:50 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=debug msg="resolved context of track" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:50 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","play_origin":"go-librespot"}} Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:50 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:50-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:42VUCXerQ5qTr4Qp6PhKo4: no supported formats" Sep 10 21:47:50 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 0 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:54 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:54 volumiopizeroa volumio[833]: info: [1757555274365] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:54 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=debug msg="resolved context of track" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:54 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","play_origin":"go-librespot"}} Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:54 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:54-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:42VUCXerQ5qTr4Qp6PhKo4: no supported formats" Sep 10 21:47:54 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:47:58 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:47:58 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:47:58 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:58 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 0 Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioGetVisibleSources Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 10 21:47:58 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 10 21:47:58 volumiopizeroa volumio[833]: info: [1757555278235] ControllerSpotify::clearAddPlayTrack Sep 10 21:47:58 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=debug msg="resolved context of track" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:47:58 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:42VUCXerQ5qTr4Qp6PhKo4","play_origin":"go-librespot"}} Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:42VUCXerQ5qTr4Qp6PhKo4" Sep 10 21:47:58 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:47:58-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:42VUCXerQ5qTr4Qp6PhKo4: no supported formats" Sep 10 21:47:58 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:48:00 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:00-04:00" level=trace msg="sent dealer ping" Sep 10 21:48:00 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:00-04:00" level=trace msg="received dealer pong" Sep 10 21:48:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 21:48:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 21:48:04 volumiopizeroa volumio[833]: info: Discovery: Getting this device information Sep 10 21:48:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioGetState Sep 10 21:48:04 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 0 Sep 10 21:48:04 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 22 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 22 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:48:12 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 22 Sep 10 21:48:12 volumiopizeroa volumio[833]: info: [1757555292602] ControllerSpotify::clearAddPlayTrack Sep 10 21:48:12 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=debug msg="resolved context of track" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:48:12 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7ne4VBA60CxGM75vw0EYad","play_origin":"go-librespot"}} Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:12 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:12-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:7ne4VBA60CxGM75vw0EYad: no supported formats" Sep 10 21:48:12 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::ClearQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::clearPlayQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42VUCXerQ5qTr4Qp6PhKo4 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3NFs3XUduzBfvc5Bx1gmzh Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6qqrTXSdwiJaq8SO0X2lSe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1mEQbSq0PCNjyL6oMgHKFQ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3cZajhyr8LmtPfHZ9296tj Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4WFgvKVfEhb3IUAFGrutTR Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2SsY5k7UWFqgye3PUMG3Oq Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FTmksd2dxiE5e3rWyJXs6 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1IfGOnXoMBkiKC9xljlSfa Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2yWlGEgEfPot0lv3OAjuG3 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5H1sKFMzDeMtXwND3V6hRY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5ITV0zqzjOYfFWpW0xBmRa Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1CPZ5BxNNd0n0nF4Orb9JS Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5BZsQlgw21vDOAjoqkNgKb Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:221eHga9f9Ne4f8D7WVgPT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:04emojnbYkrRmv5qtJcgVP Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4AajxCEwGEsmHmT4H1TwjY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SKH53SPQbEnZR4cJPVaz2 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2plbrEY59IikOBgBGLjaoe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dOtVTDdiauQNBQEDOtlAB Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:7ne4VBA60CxGM75vw0EYad Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPlay Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::play index 22 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::addQueueItems Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::addQueueItems Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0bxPRWprUVpQK0UFcddkrA Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1k2pQc5i348DCHwbn5KTdc Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3nHuKdFJZm78CoeBnDcFKe Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6dWilYAxP2aJbTTt0UsatK Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:45J4avUb9Ni0bnETYaYFVJ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:1xOqGUkyxGQRdCvGpvWKmL Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6wHpLMmp98aHcV8L1JFrj8 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6xV7Be6XEvkSnighmh2Tzj Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0FIDCNYYjNvPVimz5icugS Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:4Q0qVhFQa7j6jRKzo3HDmP Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6uiQ0DqbXTBc8tl0AUKZkR Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3hBzKgqQKkChH9hyb3MhmT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5FMyXeZ0reYloRTiCkPprT Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0InIeZW4P6VO7dUGRM4AKH Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0QCIpQV3twfqo9kh0t8Zza Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3QaPy1KgI7nu9FJEQUgn6h Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:02BcXEH1zJYbXSabPtNlKf Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:2zOmS55knKWSgScYPTNmGQ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0fK7ie6XwGxQTIkpFoWkd1 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:78nx0HDJIFD5xDq2L5420Z Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:21IYMdzTrzSe191Cy5eMap Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0GRc3eGTg8HBdWLRGYgqIc Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:5vNRhkKd0yEAg8suGBpjeY Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:0nafF9MxcXJBQWv3BTKtdF Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6iOndD4OFo7GkaDypWQIou Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:3SAga35lAPYdjj3qyfEsCF Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Adding Item to queue: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Using cached record of: spotify:track:6eLQXa6uk2EW8drsiKbABZ Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::stop Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioPushQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::saveQueue Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::play index undefined Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::updateTrackBlock Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrackBlock Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 22 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CoreStateMachine::startPlaybackTimer Sep 10 21:48:14 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 22 Sep 10 21:48:14 volumiopizeroa volumio[833]: info: [1757555294052] ControllerSpotify::clearAddPlayTrack Sep 10 21:48:14 volumiopizeroa volumio[833]: info: Sending Spotify command with payload to local API: /player/play Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=debug msg="resolved context of track" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=trace msg="emitting websocket event: will_play" Sep 10 21:48:14 volumiopizeroa volumio[833]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7ne4VBA60CxGM75vw0EYad","play_origin":"go-librespot"}} Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:7ne4VBA60CxGM75vw0EYad" Sep 10 21:48:14 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:14-04:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:7ne4VBA60CxGM75vw0EYad: no supported formats" Sep 10 21:48:14 volumiopizeroa volumio[833]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Sep 10 21:48:29 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 10 21:48:29 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:30 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:30-04:00" level=trace msg="sent dealer ping" Sep 10 21:48:30 volumiopizeroa go-librespot[1048]: time="2025-09-10T21:48:30-04:00" level=trace msg="received dealer pong" Sep 10 21:48:30 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 10 21:48:30 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 10 21:48:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 10 21:48:34 volumiopizeroa volumio[833]: info: Discovery: Getting this device information Sep 10 21:48:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::volumioGetState Sep 10 21:48:34 volumiopizeroa volumio[833]: info: CorePlayQueue::getTrack 22 Sep 10 21:48:34 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 10 21:48:35 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 10 21:48:35 volumiopizeroa volumio[833]: info: Preload queue cleared Sep 10 21:48:36 volumiopizeroa volumio[833]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 10 21:48:36 volumiopizeroa volumio[833]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 21:48:36 volumiopizeroa volumio[833]: TypeError: Cannot read property 'length' of undefined Sep 10 21:48:36 volumiopizeroa volumio[833]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 10 21:48:36 volumiopizeroa volumio[833]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 10 21:48:36 volumiopizeroa volumio[833]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 10 21:48:36 volumiopizeroa volumio[833]: at Parser.emit (events.js:315:20) Sep 10 21:48:36 volumiopizeroa volumio[833]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 10 21:48:36 volumiopizeroa volumio[833]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 10 21:48:36 volumiopizeroa volumio[833]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 10 21:48:36 volumiopizeroa volumio[833]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 10 21:48:36 volumiopizeroa volumio[833]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 10 21:48:36 volumiopizeroa volumio[833]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 10 21:48:36 volumiopizeroa volumio[833]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 10 21:48:36 volumiopizeroa volumio[833]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 10 21:48:36 volumiopizeroa volumio[833]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 10 21:48:36 volumiopizeroa volumio[833]: at IncomingMessage.emit (events.js:327:22) Sep 10 21:48:36 volumiopizeroa volumio[833]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 10 21:48:36 volumiopizeroa volumio[833]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 10 21:48:36 volumiopizeroa volumio[833]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 10 21:48:37 volumiopizeroa sudo[17330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-10 21:47 Sep 10 21:48:37 volumiopizeroa sudo[17330]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"