-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Fri 2025-12-19 21:33:58 UTC. -- Dec 19 21:32:01 muzik go-librespot[1233]: time="2025-12-19T21:32:01Z" level=debug msg="fetched chunk 8/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:32:13 muzik go-librespot[1233]: time="2025-12-19T21:32:13Z" level=debug msg="fetched chunk 9/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:32:26 muzik go-librespot[1233]: time="2025-12-19T21:32:26Z" level=debug msg="fetched chunk 10/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:32:29 muzik go-librespot[1233]: time="2025-12-19T21:32:29Z" level=trace msg="sent dealer ping" Dec 19 21:32:29 muzik go-librespot[1233]: time="2025-12-19T21:32:29Z" level=trace msg="received dealer pong" Dec 19 21:32:30 muzik go-librespot[1233]: time="2025-12-19T21:32:30Z" level=trace msg="received accesspoint ping" Dec 19 21:32:30 muzik go-librespot[1233]: time="2025-12-19T21:32:30Z" level=trace msg="received accesspoint pong ack" Dec 19 21:32:39 muzik go-librespot[1233]: time="2025-12-19T21:32:39Z" level=debug msg="fetched chunk 11/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:32:46 muzik volumio[849]: Searching plugin music_service/spop Dec 19 21:32:46 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 19 21:32:47 muzik volumio[849]: info: All search sources collected, pushing search results Dec 19 21:32:49 muzik volumio[849]: Searching plugin music_service/spop Dec 19 21:32:49 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 19 21:32:50 muzik volumio[849]: info: All search sources collected, pushing search results Dec 19 21:32:51 muzik volumio[849]: Searching plugin music_service/spop Dec 19 21:32:51 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 19 21:32:51 muzik volumio[849]: info: All search sources collected, pushing search results Dec 19 21:32:52 muzik go-librespot[1233]: time="2025-12-19T21:32:52Z" level=debug msg="fetched chunk 12/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:32:59 muzik go-librespot[1233]: time="2025-12-19T21:32:59Z" level=trace msg="sent dealer ping" Dec 19 21:32:59 muzik go-librespot[1233]: time="2025-12-19T21:32:59Z" level=trace msg="received dealer pong" Dec 19 21:33:04 muzik go-librespot[1233]: time="2025-12-19T21:33:04Z" level=debug msg="fetched chunk 13/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:33:05 muzik volumio[849]: Searching plugin music_service/spop Dec 19 21:33:05 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: spop , search Dec 19 21:33:05 muzik volumio[849]: info: All search sources collected, pushing search results Dec 19 21:33:17 muzik go-librespot[1233]: time="2025-12-19T21:33:17Z" level=debug msg="fetched chunk 14/19, size: 524288" uri="spotify:track:3eYCJXTViFwl5mdtcTsF4G" Dec 19 21:33:22 muzik volumio[849]: info: CoreCommandRouter::volumioPause Dec 19 21:33:22 muzik volumio[849]: info: CoreStateMachine::pause Dec 19 21:33:22 muzik volumio[849]: info: CoreStateMachine::stPlaybackTimer Dec 19 21:33:22 muzik volumio[849]: info: CoreStateMachine::servicePause Dec 19 21:33:22 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:22 muzik volumio[849]: info: CoreCommandRouter::servicePause Dec 19 21:33:22 muzik volumio[849]: info: Spotify Received pause Dec 19 21:33:22 muzik volumio[849]: SPOTIFY: SPOTIFY PAUSE Dec 19 21:33:22 muzik volumio[849]: SPOTIFY: {"status":"play","position":4,"title":"Summertime","artist":"Big Brother & The Holding Company","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d0000b273b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","codec":"ogg","seek":501,"duration":241,"samplerate":"320 kbps","bitdepth":"16 bit","random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":true,"stream":"spotify","volatile":false,"service":"spop"} Dec 19 21:33:22 muzik volumio[849]: info: Sending Spotify command to local API: /player/pause Dec 19 21:33:22 muzik go-librespot[1233]: time="2025-12-19T21:33:22Z" level=debug msg="pause track at 145939ms" Dec 19 21:33:23 muzik go-librespot[1233]: time="2025-12-19T21:33:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:23 muzik go-librespot[1233]: time="2025-12-19T21:33:23Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:23 muzik go-librespot[1233]: time="2025-12-19T21:33:23Z" level=trace msg="emitting websocket event: paused" Dec 19 21:33:23 muzik volumio[849]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:1IqFh00G2kvvMm8pRMpehA","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","play_origin":"go-librespot"}} Dec 19 21:33:23 muzik volumio[849]: SPOTIFY: PUSH STATE SPOTIFY Dec 19 21:33:23 muzik volumio[849]: SPOTIFY: {"status":"pause","service":"spop","title":"Summertime","artist":"Big Brother & The Holding Company, Janis Joplin","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d00001e02b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","seek":147000,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 19 21:33:23 muzik volumio[849]: info: CoreCommandRouter::servicePushState Dec 19 21:33:23 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:23 muzik volumio[849]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Summertime","artist":"Big Brother & The Holding Company, Janis Joplin","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d00001e02b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","seek":147000,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 19 21:33:23 muzik volumio[849]: verbose: CURRENT POSITION 4 Dec 19 21:33:23 muzik volumio[849]: info: CoreStateMachine::syncState stateService pause Dec 19 21:33:23 muzik volumio[849]: info: CoreStateMachine::syncState currentStatus pause Dec 19 21:33:23 muzik volumio[849]: info: CoreStateMachine::pushState Dec 19 21:33:23 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:23 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 21:33:23 muzik volumio[849]: info: CoreCommandRouter::volumioPushState Dec 19 21:33:23 muzik volumio[849]: info: CoreCommandRouter::volumioGetState Dec 19 21:33:23 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:23 muzik volumio[849]: info: CoreStateMachine::stPlaybackTimer Dec 19 21:33:23 muzik volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Dec 19 21:33:23 muzik volumio[849]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 19 21:33:23 muzik volumio[849]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 19 21:33:23 muzik volumio[849]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 19 21:33:23 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 19 21:33:23 muzik volumio[849]: info: In handleBrowseUri, curUri=spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:24 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:24 muzik volumio[849]: info: Preloading song: spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:0YwdkF50GKH8ll5ajDjKtr in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:4GGB0YKcUijytrmI3qW1nF in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:16GaM9ZjwlYrvhPbTOgBAj in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:2aOoyA2LHPZ0AiofAJkmqd in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","service":"spop","name":"Samurai Code Quote #1","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":14,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:3YgCa4PM9jaq0ForvHkXs4 in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4GGB0YKcUijytrmI3qW1nF","service":"spop","name":"Strange Eyes","artist":"Sunz Of Man","album":"Wu Tang Vs. Shaolin Masters","type":"song","duration":304,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0e1180faa72caf747d26516","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:779dCioAYbQWgFq2WvGsrc in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:5sURh0Bh2xznB6uN2XxIPV in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:24 muzik volumio[849]: info: Executing endpoint getSimilarAlbums Dec 19 21:33:24 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 19 21:33:24 muzik volumio[849]: info: Executing endpoint metavolumio Dec 19 21:33:24 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 21:33:24 muzik volumio[849]: info: Executing endpoint metavolumio Dec 19 21:33:24 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 21:33:24 muzik volumio[849]: info: Exploding uri spotify:track:6IkLNVUpwtbhl9McQw4Scb in service spop Dec 19 21:33:24 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:6yYARuT1FaSUJI1VX6Dqp8 in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:5e27uIKgcNbj6rbSiKBgFZ in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:3wcFbueXngCvMgERUGg3Ol in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aOoyA2LHPZ0AiofAJkmqd","service":"spop","name":"Zip Code","artist":"Black Knights","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:16GaM9ZjwlYrvhPbTOgBAj","service":"spop","name":"4 Sho Sho (feat. The RZA)","artist":"North Star","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":284,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3YgCa4PM9jaq0ForvHkXs4","service":"spop","name":"Samurai Code Quote #2","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":17,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:2lD2SLZljGarm78UMgJlxy in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:779dCioAYbQWgFq2WvGsrc","service":"spop","name":"Cakes","artist":"Kool G Rap","album":"Genius Of Rap 3","type":"song","duration":300,"albumart":"https://i.scdn.co/image/ab67616d0000b27303a340637f76b102d8527921","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:5dB02vHFlXxSlSbppp0XZS in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5sURh0Bh2xznB6uN2XxIPV","service":"spop","name":"Samurai Code Quote #3","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":17,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6IkLNVUpwtbhl9McQw4Scb","service":"spop","name":"Don't Test/Wu Stallion","artist":"Suga Bang Bang","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":318,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:0PhRyOK4xUtXMwvK2oHPiF in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5e27uIKgcNbj6rbSiKBgFZ","service":"spop","name":"The Man","artist":"Masta Killa","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":253,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6yYARuT1FaSUJI1VX6Dqp8","service":"spop","name":"Walking Through the Darkness","artist":"Tekitha","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":317,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:2Mh03zbNgdy6fNFYksigFh in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3wcFbueXngCvMgERUGg3Ol","service":"spop","name":"Samurai Code Quote #4","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":13,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:2ppg0MGRzLN7nAUX7cbCxj in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2lD2SLZljGarm78UMgJlxy","service":"spop","name":"Walk The Dogs","artist":"Royal Fam","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:5WWTUHlFUOMA7VIxlTf3ZW in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5dB02vHFlXxSlSbppp0XZS","service":"spop","name":"Stay With Me","artist":"Melodie","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:1C8pCUu3RJkg44BAa50cqX in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0PhRyOK4xUtXMwvK2oHPiF","service":"spop","name":"East New York Stamp","artist":"Jeru","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":124,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: info: Exploding uri spotify:track:2a5QWKtnVLRP6t5k3HQUG5 in service spop Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Mh03zbNgdy6fNFYksigFh","service":"spop","name":"Samurai Code Quote #5","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":32,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WWTUHlFUOMA7VIxlTf3ZW","service":"spop","name":"Samurai Code Quote #6","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":20,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1C8pCUu3RJkg44BAa50cqX","service":"spop","name":"Samurai Showdown","artist":"RZA","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ppg0MGRzLN7nAUX7cbCxj","service":"spop","name":"Fast Shadow (feat. Method Man, Ol' Dirty Bastard, RZA, U-God & Masta Killa)","artist":"Wu-Tang Clan","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":181,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:25 muzik volumio[849]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2a5QWKtnVLRP6t5k3HQUG5","service":"spop","name":"Samurai Code Final Quote","artist":"Forest Whitaker","album":"Ghost Dog: The Way of the Samurai - The Album","type":"song","duration":34,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9b93e07f2d97fb84677d31","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Dec 19 21:33:28 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::stPlaybackTimer Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::pushState Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioPushState Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioGetState Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::serviceStop Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::serviceStop Dec 19 21:33:28 muzik volumio[849]: info: Spotify Stop Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: SPOTIFY STOP Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: {"status":"pause","position":4,"title":"Summertime","artist":"Big Brother & The Holding Company","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d0000b273b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","codec":"ogg","seek":143924,"duration":241,"samplerate":"320 kbps","bitdepth":"16 bit","random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":false,"mute":true,"stream":"spotify","volatile":false,"service":"spop"} Dec 19 21:33:28 muzik volumio[849]: info: Sending Spotify command to local API: /player/pause Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:28 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:28 muzik volumio[849]: info: Adding Item to queue: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:28 muzik volumio[849]: info: Exploding uri spotify:album:5sJPQsnIokwcjyFVeC8QOK in service spop Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: EXPLODING URI:spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Dec 19 21:33:28 muzik go-librespot[1233]: time="2025-12-19T21:33:28Z" level=debug msg="pause track at 146345ms" Dec 19 21:33:28 muzik go-librespot[1233]: time="2025-12-19T21:33:28Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:28 muzik go-librespot[1233]: time="2025-12-19T21:33:28Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:28 muzik go-librespot[1233]: time="2025-12-19T21:33:28Z" level=trace msg="emitting websocket event: paused" Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:1IqFh00G2kvvMm8pRMpehA","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","play_origin":"go-librespot"}} Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: PUSH STATE SPOTIFY Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: {"status":"pause","service":"spop","title":"Summertime","artist":"Big Brother & The Holding Company, Janis Joplin","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d00001e02b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","seek":147000,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::servicePushState Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Summertime","artist":"Big Brother & The Holding Company, Janis Joplin","album":"Cheap Thrills","albumart":"https://i.scdn.co/image/ab67616d00001e02b6bd5786c0a512ec463f3cb7","uri":"spotify:track:3eYCJXTViFwl5mdtcTsF4G","trackType":"spotify","seek":147000,"duration":241,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 19 21:33:28 muzik volumio[849]: verbose: CURRENT POSITION 4 Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::syncState stateService pause Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::syncState currentStatus stop Dec 19 21:33:28 muzik volumio[849]: info: CoreStateMachine::pushState Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioPushState Dec 19 21:33:28 muzik volumio[849]: info: CoreCommandRouter::volumioGetState Dec 19 21:33:28 muzik volumio[849]: info: CorePlayQueue::getTrack 4 Dec 19 21:33:28 muzik volumio[849]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Dec 19 21:33:29 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:29 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:29 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:29 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:29 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:29 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:29 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:29 muzik volumio[849]: info: [1766180009045] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:29 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=trace msg="sent dealer ping" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=trace msg="received dealer pong" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:29 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:29 muzik go-librespot[1233]: time="2025-12-19T21:33:29Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:29 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:37 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:37 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:37 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:37 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:37 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:37 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:37 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:37 muzik volumio[849]: info: Adding Item to queue: spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:37 muzik volumio[849]: info: Using cached record of: spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:37 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:37 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:37 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:37 muzik volumio[849]: info: [1766180017775] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:37 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:37 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:37 muzik go-librespot[1233]: time="2025-12-19T21:33:37Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:37 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:42 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:42 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:42 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:42 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:42 muzik volumio[849]: info: Adding Item to queue: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:42 muzik volumio[849]: info: Using cached record of: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:42 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:42 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:42 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:42 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:42 muzik volumio[849]: info: [1766180022294] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:42 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:42 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:42 muzik go-librespot[1233]: time="2025-12-19T21:33:42Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:42 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:47 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:47 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:47 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:47 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:47 muzik volumio[849]: info: Adding Item to queue: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:47 muzik volumio[849]: info: Using cached record of: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:47 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:47 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:47 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:47 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:47 muzik volumio[849]: info: [1766180027587] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:47 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:47 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:47 muzik go-librespot[1233]: time="2025-12-19T21:33:47Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:47 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:48 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:48 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:48 muzik volumio[849]: info: Adding Item to queue: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:48 muzik volumio[849]: info: Using cached record of: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:48 muzik volumio[849]: info: [1766180028076] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:48 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:48 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:48 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:48 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:48 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:48 muzik volumio[849]: info: Adding Item to queue: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:48 muzik volumio[849]: info: Using cached record of: spotify:album:5sJPQsnIokwcjyFVeC8QOK Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:48 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::play index 0 Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:48 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:48 muzik volumio[849]: info: CorePlayQueue::getTrack 0 Dec 19 21:33:48 muzik volumio[849]: info: [1766180028506] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:48 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="resolved context of track" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:48 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","uri":"spotify:track:0YwdkF50GKH8ll5ajDjKtr","play_origin":"go-librespot"}} Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:0YwdkF50GKH8ll5ajDjKtr" Dec 19 21:33:48 muzik go-librespot[1233]: time="2025-12-19T21:33:48Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0YwdkF50GKH8ll5ajDjKtr: no supported formats" Dec 19 21:33:48 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:52 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:52 muzik volumio[849]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::ClearQueue Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::clearPlayQueue Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:52 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:52 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:0YwdkF50GKH8ll5ajDjKtr Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:4GGB0YKcUijytrmI3qW1nF Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:16GaM9ZjwlYrvhPbTOgBAj Dec 19 21:33:52 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:52 muzik volumio[849]: info: CoreCommandRouter::volumioPlay Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::play index 2 Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::addQueueItems Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::addQueueItems Dec 19 21:33:52 muzik volumio[849]: info: Preload queue cleared Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:2aOoyA2LHPZ0AiofAJkmqd Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:3YgCa4PM9jaq0ForvHkXs4 Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:779dCioAYbQWgFq2WvGsrc Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:5sURh0Bh2xznB6uN2XxIPV Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:6IkLNVUpwtbhl9McQw4Scb Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:6yYARuT1FaSUJI1VX6Dqp8 Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:5e27uIKgcNbj6rbSiKBgFZ Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:3wcFbueXngCvMgERUGg3Ol Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:2lD2SLZljGarm78UMgJlxy Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:5dB02vHFlXxSlSbppp0XZS Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:0PhRyOK4xUtXMwvK2oHPiF Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:2Mh03zbNgdy6fNFYksigFh Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:2ppg0MGRzLN7nAUX7cbCxj Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:5WWTUHlFUOMA7VIxlTf3ZW Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:1C8pCUu3RJkg44BAa50cqX Dec 19 21:33:52 muzik volumio[849]: info: Adding Item to queue: spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:52 muzik volumio[849]: info: Using cached record of: spotify:track:2a5QWKtnVLRP6t5k3HQUG5 Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::stop Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:52 muzik volumio[849]: info: CoreCommandRouter::volumioPushQueue Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::saveQueue Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::play index undefined Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::updateTrackBlock Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::getTrackBlock Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::getTrack 2 Dec 19 21:33:52 muzik volumio[849]: info: CoreStateMachine::startPlaybackTimer Dec 19 21:33:52 muzik volumio[849]: info: CorePlayQueue::getTrack 2 Dec 19 21:33:52 muzik volumio[849]: info: [1766180032466] ControllerSpotify::clearAddPlayTrack Dec 19 21:33:52 muzik volumio[849]: info: Sending Spotify command with payload to local API: /player/play Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=debug msg="resolved context of track" uri="spotify:track:16GaM9ZjwlYrvhPbTOgBAj" Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:16GaM9ZjwlYrvhPbTOgBAj" Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:16GaM9ZjwlYrvhPbTOgBAj" Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=trace msg="emitting websocket event: will_play" Dec 19 21:33:52 muzik volumio[849]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:16GaM9ZjwlYrvhPbTOgBAj","uri":"spotify:track:16GaM9ZjwlYrvhPbTOgBAj","play_origin":"go-librespot"}} Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=warning msg="original track has no formats, alternatives have a total of 0" uri="spotify:track:16GaM9ZjwlYrvhPbTOgBAj" Dec 19 21:33:52 muzik go-librespot[1233]: time="2025-12-19T21:33:52Z" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:16GaM9ZjwlYrvhPbTOgBAj: no supported formats" Dec 19 21:33:52 muzik volumio[849]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 19 21:33:58 muzik volumio[849]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 19 21:33:58 muzik volumio[849]: info: PLUGIN onReboot : networkfs Dec 19 21:33:58 muzik volumio[849]: info: PLUGIN onReboot : audiophonicsonoff Dec 19 21:33:58 muzik volumio[849]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 21:33:58 muzik volumio[849]: TypeError: Cannot read property 'writeSync' of undefined Dec 19 21:33:58 muzik volumio[849]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Dec 19 21:33:58 muzik volumio[849]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 19 21:33:58 muzik volumio[849]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 19 21:33:58 muzik volumio[849]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 19 21:33:58 muzik volumio[849]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 19 21:33:58 muzik volumio[849]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 19 21:33:58 muzik volumio[849]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Dec 19 21:33:58 muzik volumio[849]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Dec 19 21:33:58 muzik volumio[849]: at Socket.emit (events.js:315:20) Dec 19 21:33:58 muzik volumio[849]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 19 21:33:58 muzik volumio[849]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 19 21:33:58 muzik volumio[849]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 21:33:58 muzik sudo[5321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-19 21:32 Dec 19 21:33:58 muzik sudo[5321]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"