-- Logs begin at Sat 2024-11-23 17:51:37 -08, end at Sat 2024-11-23 20:13:13 -08. -- Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","service":"spop","name":"眼淚記得你 - 電影《想見你》主題曲","artist":"Shi Shi","album":"眼淚記得你","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b273162f07a534e697ed177d8085","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:7A8oik3md31IzkgyDur4md in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:3SJgTyb8a7z66suw0kBZ0T in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0DHk1BgLDEddRvZ4wy5s6a","service":"spop","name":"就忘了吧","artist":"1K","album":"就忘了吧","type":"song","duration":203,"albumart":"https://i.scdn.co/image/ab67616d0000b273e76bd1bec24b4f03b76a0339","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4TJ4AEPBhqj9xYykVBU4GL","service":"spop","name":"11","artist":"G.E.M.","album":"11","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b2733bc0195b744db7a23c37191b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:1J6M0Lw3Gyam7qL4ndlXbF in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:0gAHXdw7PmB1YWnOEKggcU in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:7xLHcay4KRCdl9MqXnyrW0 in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7cwB09xvS1ArgeXozdf3dU","service":"spop","name":"只想把你偷偷藏好(《偷偷藏不住》电视剧主题曲/片头曲)","artist":"Silence Wang","album":"只想把你偷偷藏好(《偷偷藏不住》电视剧主题曲/片头曲)","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2739074f4c5a459ebe099ba7220","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:1JUvV9eEZuws2YpA1p0e6o in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:2FaK4cjKKXupA0klPJTuVX in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ILsHsOAEi2QdYxgFVQ0nM","service":"spop","name":"如常","artist":"Nicky Lee","album":"如常","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b273889b1652ec90c8aba2a37c1f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:5HGvMmgabU7ZkF94klVhdm in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1cvXawBqcyifKuKD2IdQpS","service":"spop","name":"連輸入法都記得你 - 《HIStory5-遇見未來的你》LINE TV片尾曲","artist":"Andrew Tan","album":"連輸入法都記得你 (《HIStory5-遇見未來的你》LINE TV片尾曲)","type":"song","duration":267,"albumart":"https://i.scdn.co/image/ab67616d0000b273695ab8373ddd29d2beda826f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1MzomU1zM1oEuyIAJ1upfu","service":"spop","name":"感情用事","artist":"Cyndi Wang","album":"BITE BACK","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b27357513d9f849d8b87d1732025","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:2wl5Jlf09WAV7LIVUNK594 in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2OL3D6q6Qs1PY0NV3u4dNu","service":"spop","name":"缸","artist":"No Party For Cao Dong","album":"瓦合","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2731fb78bbf9355ff55c3f6aac3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:6qnSA1GSwRtgHCqaD1jQin in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:08lvLdFzi5oNDhWVn58RWO","service":"spop","name":"還在流浪","artist":"Jay Chou","album":"最偉大的作品","type":"song","duration":265,"albumart":"https://i.scdn.co/image/ab67616d0000b273283111b93a25fffd9d6f050d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:53yiHIP3ptxWtT4ambRcgb in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5bhHRGoY7kCAztNSDx74cs","service":"spop","name":"来迟","artist":"戴羽彤","album":"来迟","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b27352d74b91cdae4693f5a7d99a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:6UXPAEteXJEyxcA0MAo8Zf in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:6tIaVtOWhsJhOYT81hioxk in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:70ywpHtaToCmnULRT9Vu4p in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:2OR5FUKy3GQ7I9DCbRnfsW in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:5DCNAwyomUF3LXunq4NSpJ in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1J6M0Lw3Gyam7qL4ndlXbF","service":"spop","name":"長成什麼樣子算愛情","artist":"Mixer","album":"長成什麼樣子算愛情","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273df22f07c8d42bea9bc2d6e62","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:5FbxrTeMrO3zbLe3uLbYH7 in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7EzeNbQHT6DHxQty56qsbN","service":"spop","name":"你的情歌 (電影《你的情歌》主題曲)","artist":"TANK 呂建忠","album":"你的情歌 (電影《你的情歌》主題曲)","type":"song","duration":283,"albumart":"https://i.scdn.co/image/ab67616d0000b273fc6d80236a07c9e15b3561a4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:4BGkSCrjiLao1bpibNptTD in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6la3Kme7O7D01fJPxgpAS5","service":"spop","name":"訣愛 (劇集《蒼蘭訣》片頭曲)","artist":"詹雯婷","album":"訣愛","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf30026cc66e565cfe18b116","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7xLHcay4KRCdl9MqXnyrW0","service":"spop","name":"最美的遺憾","artist":"Sam Lee","album":"Face II 真・裏","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273f63e57ac22abecb2440d06e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7A8oik3md31IzkgyDur4md","service":"spop","name":"平分慚愧","artist":"Feng Ze","album":"平分慚愧","type":"song","duration":283,"albumart":"https://i.scdn.co/image/ab67616d0000b2734831032508b4edf9ffbceefb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:69AvckyixY1AIGRYDZ1QnO in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:00 volumio go-librespot[5242]: time="2024-11-23T20:12:00-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:00 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:00 volumio volumio[5023]: info: Exploding uri spotify:track:4ctSTDHcpW4bz90mDvjJcv in service spop Nov 23 20:12:00 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5HGvMmgabU7ZkF94klVhdm","service":"spop","name":"清空","artist":"苏星婕","album":"清空","type":"song","duration":177,"albumart":"https://i.scdn.co/image/ab67616d0000b273404da35c2e20da9c3865903a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:5VymCvfV0FguCa7zrIPSgk in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:3tHqubDUl1OHGgGz87YreF in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0gAHXdw7PmB1YWnOEKggcU","service":"spop","name":"希望你被这个世界爱着","artist":"吕口口","album":"希望你被这个世界爱着","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b2731552b9fc29f53a9115659ed0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:3UrBBzW0iYXg579Qdbb53l in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6pFxVcn6T4BdP8mfZpTQHZ","service":"spop","name":"想和你看五月的晚霞","artist":"陳華","album":"華與浪漫","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b273ef4230aeee6906b9552768cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:6us212S3fCRIQwOwNExqLH in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1JUvV9eEZuws2YpA1p0e6o","service":"spop","name":"总会有人","artist":"向思思","album":"总会有人","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273bf5939b195f88d0d6768eff8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6UXPAEteXJEyxcA0MAo8Zf","service":"spop","name":"大人","artist":"Fish Leong","album":"麋鹿","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b273e53c83f09609b7ccb08da1f2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:4Zwn8WxD61diTwqv9hyTQA in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:6jNy9WM3zyvG9E5bdVALl8 in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FaK4cjKKXupA0klPJTuVX","service":"spop","name":"夢裡的女孩 - 電影《想見你》插曲","artist":"Nine Chen","album":"夢裡的女孩","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273e78c05cfced4c1e5488c4df5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:70ywpHtaToCmnULRT9Vu4p","service":"spop","name":"親愛的對象 - 《關於我和鬼變成家人的那件事》電影主題曲","artist":"Jolin Tsai","album":"親愛的對象 (《關於我和鬼變成家人的那件事》電影主題曲)","type":"song","duration":294,"albumart":"https://i.scdn.co/image/ab67616d0000b27388ce60fb0b5ef4c7e8aa0862","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6tIaVtOWhsJhOYT81hioxk","service":"spop","name":"內傷","artist":"Ricky Hsiao","album":"說故事的歌","type":"song","duration":292,"albumart":"https://i.scdn.co/image/ab67616d0000b273d26a737ddf64994fd2e2ae2e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:4NF4arILnIdBOZu2FkRV8P in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SJgTyb8a7z66suw0kBZ0T","service":"spop","name":"失重前幸福","artist":"艾薇","album":"I","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b273c7dd9f3f5921f3ad7a558eda","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qnSA1GSwRtgHCqaD1jQin","service":"spop","name":"今天陽光就是特別耀眼特別和諧 feat. 韋禮安","artist":"Pets Tseng","album":"今天陽光就是特別耀眼特別和諧","type":"song","duration":186,"albumart":"https://i.scdn.co/image/ab67616d0000b2730040bde9935080d36c2fb107","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:1bQHKplf5fkkecalhAgYp0 in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2wl5Jlf09WAV7LIVUNK594","service":"spop","name":"一定會","artist":"JJ Lin","album":"一定會","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b273ee67e7de3b4d54ec6db00f25","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:5eDJH4MfpRQM9S9WTiu7yU in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4BGkSCrjiLao1bpibNptTD","service":"spop","name":"四季予你","artist":"程響","album":"四季予你","type":"song","duration":248,"albumart":"https://i.scdn.co/image/ab67616d0000b2737be012a78642eb8ff67b16d7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:53yiHIP3ptxWtT4ambRcgb","service":"spop","name":"終究還是因為愛","artist":"TRASH","album":"Holy Trip!","type":"song","duration":285,"albumart":"https://i.scdn.co/image/ab67616d0000b2736fe6c96efd4dce11eab39403","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:1qgb3pIoFgsOOAWERPaAAs in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:3gsBzDDsdCEGSGNjbs5NL8 in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:7ngCnNNMl7KrpUkmZMwbeC in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:0FfNFvy5KRRyB2snvK39zL in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:3SSUUro7FoNAWQEa01xtZY in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2OR5FUKy3GQ7I9DCbRnfsW","service":"spop","name":"最後一堂課 - 《媽，別鬧了！》影集片尾曲","artist":"Eric Chou","album":"最後一堂課 (《媽，別鬧了！》影集片尾曲)","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b273ded6b4e5ad236f1dcdfa78b6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5FbxrTeMrO3zbLe3uLbYH7","service":"spop","name":"你不會一輩子的愛上我","artist":"Tyson Yoshi","album":"你不會一輩子的愛上我","type":"song","duration":197,"albumart":"https://i.scdn.co/image/ab67616d0000b27341183d5aeed29d56156ec782","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3tHqubDUl1OHGgGz87YreF","service":"spop","name":"500天","artist":"Sophie Chen","album":"500天","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b273abff0ae69708c89627aee591","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:3rjE1K6ASuHzYC1ETM4UFa in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:72OVnXDzugvrCU25lMi9au in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:69AvckyixY1AIGRYDZ1QnO","service":"spop","name":"我很好騙","artist":"Power Station","album":"都是因為愛","type":"song","duration":313,"albumart":"https://i.scdn.co/image/ab67616d0000b2736a2a8ef079f7d82b882c268a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6us212S3fCRIQwOwNExqLH","service":"spop","name":"愛我的時候","artist":"Eric Chou","album":"愛我的時候","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273b29811fb08b629973cd84fb6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: info: Exploding uri spotify:track:4PMakIBWXujbe2MIsuZtOc in service spop Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4ctSTDHcpW4bz90mDvjJcv","service":"spop","name":"女字旁 (《台北女子圖鑑》戲劇片尾曲)","artist":"Della","album":"女字旁","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b27363877daf994ebb9361c9af64","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5VymCvfV0FguCa7zrIPSgk","service":"spop","name":"毒藥","artist":"蕭秉治","album":"狂人","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b273172afa2c2cc90339c0389466","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:01 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6jNy9WM3zyvG9E5bdVALl8","service":"spop","name":"好不容易 (《華燈初上》片尾曲)","artist":"告五人","album":"好不容易","type":"song","duration":295,"albumart":"https://i.scdn.co/image/ab67616d0000b273b031ee53413ef37f35096a14","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DCNAwyomUF3LXunq4NSpJ","service":"spop","name":"阿拉斯加海湾","artist":"蔡恩雨 Priscilla Abby","album":"阿拉斯加海湾","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273f7a692030012a4616cc7cb04","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3UrBBzW0iYXg579Qdbb53l","service":"spop","name":"我反芻著你留下的寂寞","artist":"理想混蛋","album":"關掉 / 打開","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b27372c6a3908a663c6c7cdc17c6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4Zwn8WxD61diTwqv9hyTQA","service":"spop","name":"閣愛妳一擺","artist":"EggPlantEgg","album":"閣愛妳一擺","type":"song","duration":278,"albumart":"https://i.scdn.co/image/ab67616d0000b273f96bddd02ddbb1017d028d17","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4NF4arILnIdBOZu2FkRV8P","service":"spop","name":"你是我這輩子最想愛的呀","artist":"五堅情WOLF(S) (邱鋒澤、陳零九、黃偉晉、賴晏駒、婁峻碩)","album":"你是我這輩子最想愛的呀","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b273c314285731d06d6054be18ef","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SSUUro7FoNAWQEa01xtZY","service":"spop","name":"我們到底算什麼","artist":"徐暐翔","album":"躍","type":"song","duration":227,"albumart":"https://i.scdn.co/image/ab67616d0000b27302d302228f6691e0ac55e5c0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5eDJH4MfpRQM9S9WTiu7yU","service":"spop","name":"11 (with Hooleeger)","artist":"隊長","album":"11 (with Hooleeger)","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b27327607a80fd3c7614f43629f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1qgb3pIoFgsOOAWERPaAAs","service":"spop","name":"同感","artist":"郁可唯","album":"同感","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b273df4ba8d976281dfb098756ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3gsBzDDsdCEGSGNjbs5NL8","service":"spop","name":"別離開好嗎 (《第9節課》插曲)","artist":"HAOR","album":"別離開好嗎 (《第9節課》插曲)","type":"song","duration":247,"albumart":"https://i.scdn.co/image/ab67616d0000b273a2f48bfddac56eb2bf58cc22","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7ngCnNNMl7KrpUkmZMwbeC","service":"spop","name":"餘人節快樂","artist":"施語庭","album":"餘人節快樂","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b273c17419ff5b5cf192206dcb93","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:02 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:02 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:02 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:02 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:02 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:02 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:02 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:02 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:02 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:02 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:02 volumio volumio[5023]: info: Adding Item to queue: spotify:user:spotify:playlist:37i9dQZF1DWUKOGt2rVvOT Nov 23 20:12:02 volumio volumio[5023]: info: Exploding uri spotify:user:spotify:playlist:37i9dQZF1DWUKOGt2rVvOT in service spop Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: EXPLODING URI:spotify:user:spotify:playlist:37i9dQZF1DWUKOGt2rVvOT Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0FfNFvy5KRRyB2snvK39zL","service":"spop","name":"I'm Alive","artist":"W.M.L","album":"I'm Alive","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b2732b2fe08e9f9599f0c14cb57a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:72OVnXDzugvrCU25lMi9au","service":"spop","name":"如果可以 - 電影\"月老\"主題曲","artist":"WeiBird","album":"如果可以 (電影\"月老\"主題曲)","type":"song","duration":274,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8bd453784ae431700a851b0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3rjE1K6ASuHzYC1ETM4UFa","service":"spop","name":"等待被理解的人","artist":"張若凡","album":"等待被理解的人","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273e05866cc5fa3e4a240b67cb0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:02 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4PMakIBWXujbe2MIsuZtOc","service":"spop","name":"對等關係 (feat. 張惠妹)","artist":"Ronghao Li","album":"對等關係 (feat. 張惠妹)","type":"song","duration":327,"albumart":"https://i.scdn.co/image/ab67616d0000b27361bfccc9a04bb8afd9cb7bb2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:03 volumio volumio[5023]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1bQHKplf5fkkecalhAgYp0","service":"spop","name":"摯友","artist":"A-Lin","album":"LINK","type":"song","duration":318,"albumart":"https://i.scdn.co/image/ab67616d0000b273df9e9e737bb6e2053de88684","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:12:04 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:04 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:04 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:04 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:04 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:04 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:04 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:04 volumio volumio[5023]: info: [1732421524756] ControllerSpotify::clearAddPlayTrack Nov 23 20:12:04 volumio volumio[5023]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:12:05 volumio go-librespot[5242]: time="2024-11-23T20:12:05-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:05 volumio go-librespot[5242]: time="2024-11-23T20:12:05-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:05 volumio go-librespot[5242]: time="2024-11-23T20:12:05-08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:05 volumio go-librespot[5242]: time="2024-11-23T20:12:05-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:05 volumio go-librespot[5242]: time="2024-11-23T20:12:05-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:05 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:06 volumio go-librespot[5242]: time="2024-11-23T20:12:06-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:06 volumio go-librespot[5242]: time="2024-11-23T20:12:06-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:06 volumio go-librespot[5242]: time="2024-11-23T20:12:06-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:06 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:07 volumio volumio[5023]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 23 20:12:07 volumio volumio[5023]: info: Retrying Login Due to Network Error Nov 23 20:12:07 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:07 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:07 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:07 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:07 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:07 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:07 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:07 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:07 volumio volumio[5023]: info: Using cached record of: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:07 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:07 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:07 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:07 volumio volumio[5023]: info: [1732421527816] ControllerSpotify::clearAddPlayTrack Nov 23 20:12:07 volumio volumio[5023]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:12:08 volumio go-librespot[5242]: time="2024-11-23T20:12:08-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:08 volumio go-librespot[5242]: time="2024-11-23T20:12:08-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:08 volumio go-librespot[5242]: time="2024-11-23T20:12:08-08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:08 volumio go-librespot[5242]: time="2024-11-23T20:12:08-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:08 volumio go-librespot[5242]: time="2024-11-23T20:12:08-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:08 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:09 volumio go-librespot[5242]: time="2024-11-23T20:12:09-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:09 volumio go-librespot[5242]: time="2024-11-23T20:12:09-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:09 volumio go-librespot[5242]: time="2024-11-23T20:12:09-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:09 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:09 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:09 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:09 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:09 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:09 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:09 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:09 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:09 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:09 volumio volumio[5023]: info: Using cached record of: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:09 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:09 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:09 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:09 volumio volumio[5023]: info: [1732421529661] ControllerSpotify::clearAddPlayTrack Nov 23 20:12:09 volumio volumio[5023]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:10 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:10 volumio go-librespot[5242]: time="2024-11-23T20:12:10-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:10 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:12 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:12 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:12 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:0USeB0Tykai3QZj5YAUclG Nov 23 20:12:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:12 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:0DHk1BgLDEddRvZ4wy5s6a Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4TJ4AEPBhqj9xYykVBU4GL Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4ILsHsOAEi2QdYxgFVQ0nM Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:7cwB09xvS1ArgeXozdf3dU Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:08lvLdFzi5oNDhWVn58RWO Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5bhHRGoY7kCAztNSDx74cs Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1cvXawBqcyifKuKD2IdQpS Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1MzomU1zM1oEuyIAJ1upfu Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:2OL3D6q6Qs1PY0NV3u4dNu Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6pFxVcn6T4BdP8mfZpTQHZ Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:7EzeNbQHT6DHxQty56qsbN Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6la3Kme7O7D01fJPxgpAS5 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:7A8oik3md31IzkgyDur4md Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3SJgTyb8a7z66suw0kBZ0T Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1J6M0Lw3Gyam7qL4ndlXbF Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:0gAHXdw7PmB1YWnOEKggcU Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:7xLHcay4KRCdl9MqXnyrW0 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1JUvV9eEZuws2YpA1p0e6o Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:2FaK4cjKKXupA0klPJTuVX Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5HGvMmgabU7ZkF94klVhdm Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:2wl5Jlf09WAV7LIVUNK594 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6qnSA1GSwRtgHCqaD1jQin Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:53yiHIP3ptxWtT4ambRcgb Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6UXPAEteXJEyxcA0MAo8Zf Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6tIaVtOWhsJhOYT81hioxk Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:70ywpHtaToCmnULRT9Vu4p Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:2OR5FUKy3GQ7I9DCbRnfsW Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5DCNAwyomUF3LXunq4NSpJ Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5FbxrTeMrO3zbLe3uLbYH7 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4BGkSCrjiLao1bpibNptTD Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:69AvckyixY1AIGRYDZ1QnO Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4ctSTDHcpW4bz90mDvjJcv Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5VymCvfV0FguCa7zrIPSgk Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3tHqubDUl1OHGgGz87YreF Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3UrBBzW0iYXg579Qdbb53l Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6us212S3fCRIQwOwNExqLH Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4Zwn8WxD61diTwqv9hyTQA Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:6jNy9WM3zyvG9E5bdVALl8 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4NF4arILnIdBOZu2FkRV8P Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1bQHKplf5fkkecalhAgYp0 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:5eDJH4MfpRQM9S9WTiu7yU Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:1qgb3pIoFgsOOAWERPaAAs Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3gsBzDDsdCEGSGNjbs5NL8 Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:7ngCnNNMl7KrpUkmZMwbeC Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:0FfNFvy5KRRyB2snvK39zL Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3SSUUro7FoNAWQEa01xtZY Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:3rjE1K6ASuHzYC1ETM4UFa Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:72OVnXDzugvrCU25lMi9au Nov 23 20:12:12 volumio volumio[5023]: info: Adding Item to queue: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:12 volumio volumio[5023]: info: Using cached record of: spotify:track:4PMakIBWXujbe2MIsuZtOc Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:12 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:12 volumio volumio[5023]: info: [1732421532062] ControllerSpotify::clearAddPlayTrack Nov 23 20:12:12 volumio volumio[5023]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:12:12 volumio go-librespot[5242]: time="2024-11-23T20:12:12-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:12 volumio go-librespot[5242]: time="2024-11-23T20:12:12-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:12 volumio go-librespot[5242]: time="2024-11-23T20:12:12-08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:12 volumio go-librespot[5242]: time="2024-11-23T20:12:12-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:12 volumio go-librespot[5242]: time="2024-11-23T20:12:12-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:12 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:13 volumio go-librespot[5242]: time="2024-11-23T20:12:13-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:13 volumio go-librespot[5242]: time="2024-11-23T20:12:13-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:13 volumio go-librespot[5242]: time="2024-11-23T20:12:13-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:13 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:16 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:16 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:16 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:16 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:16 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:16 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:16 volumio volumio[5023]: info: [1732421536259] ControllerSpotify::clearAddPlayTrack Nov 23 20:12:16 volumio volumio[5023]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:12:16 volumio go-librespot[5242]: time="2024-11-23T20:12:16-08:00" level=debug msg="resolved context of track" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:16 volumio go-librespot[5242]: time="2024-11-23T20:12:16-08:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:16 volumio go-librespot[5242]: time="2024-11-23T20:12:16-08:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:17 volumio go-librespot[5242]: time="2024-11-23T20:12:17-08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:12:17 volumio go-librespot[5242]: time="2024-11-23T20:12:17-08:00" level=trace msg="emitting websocket event: will_play" Nov 23 20:12:17 volumio volumio[5023]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0USeB0Tykai3QZj5YAUclG","play_origin":"go-librespot"}} Nov 23 20:12:17 volumio volumio[5023]: info: MyVolumio login type: Token Nov 23 20:12:17 volumio go-librespot[5242]: time="2024-11-23T20:12:17-08:00" level=debug msg="selected format OGG_VORBIS_320 (6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c)" uri="spotify:track:0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:17 volumio go-librespot[5242]: time="2024-11-23T20:12:17-08:00" level=debug msg="requested aes key for file 6355d87ce860d0bd22cbe5d5bf325aaf0a867b0c, gid: 0USeB0Tykai3QZj5YAUclG" Nov 23 20:12:17 volumio go-librespot[5242]: time="2024-11-23T20:12:17-08:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:0USeB0Tykai3QZj5YAUclG: failed retrieving audio key: failed retrieving aes key with code 1" Nov 23 20:12:17 volumio volumio[5023]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Nov 23 20:12:28 volumio volumio[5023]: info: CoreCommandRouter::volumioGetState Nov 23 20:12:28 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:33 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 23 20:12:34 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:36 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Nov 23 20:12:38 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:38 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:38 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:38 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:38 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:38 volumio volumio[5023]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 Nov 23 20:12:38 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 23 20:12:38 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:38 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:38 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:38 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:38 volumio volumio[5023]: info: [1732421558761] ControllerWebradio::clearAddPlayTrack Nov 23 20:12:38 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 20:12:38 volumio volumio[5023]: info: sendMpdCommand stop took 2 milliseconds Nov 23 20:12:38 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 20:12:38 volumio volumio[5023]: info: Nov 23 20:12:38 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:38 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:38 volumio volumio[5023]: info: sendMpdCommand clear took 0 milliseconds Nov 23 20:12:38 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" Nov 23 20:12:38 volumio volumio[5023]: error: updateQueue error: null Nov 23 20:12:38 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:12:38 volumio volumio[5023]: info: Nov 23 20:12:38 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:38 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:38 volumio volumio[5023]: info: Nov 23 20:12:38 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:38 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:40 volumio volumio[5023]: info: Nov 23 20:12:40 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:40 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:40 volumio volumio[5023]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 1509 milliseconds Nov 23 20:12:40 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 20:12:40 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand play Nov 23 20:12:40 volumio volumio[5023]: info: ------------------------------ 1508ms Nov 23 20:12:40 volumio volumio[5023]: info: ------------------------------ 1508ms Nov 23 20:12:40 volumio volumio[5023]: info: ------------------------------ 2ms Nov 23 20:12:40 volumio volumio[5023]: info: Nov 23 20:12:40 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:40 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:40 volumio volumio[5023]: info: Nov 23 20:12:40 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:40 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:40 volumio volumio[5023]: info: sendMpdCommand play took 2 milliseconds Nov 23 20:12:40 volumio volumio[5023]: info: ------------------------------ 1ms Nov 23 20:12:40 volumio volumio[5023]: info: ------------------------------ 1ms Nov 23 20:12:41 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:12:41 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:12:41 volumio volumio[5023]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99604785 Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: [1732421561044] ControllerWebradio::clearAddPlayTrack Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand stop took 141 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:41 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:41 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand clear took 3 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99604785" Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:41 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 12ms Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: error: updateQueue error: null Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand status took 13 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 13ms Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:41 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:41 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:41 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:41 volumio volumio[5023]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 20:12:41 volumio volumio[5023]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 23ms Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 25ms Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99604785" took 237 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 20:12:41 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand play Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 227ms Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 228ms Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: info: Nov 23 20:12:41 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:41 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:41 volumio volumio[5023]: info: sendMpdCommand play took 3 milliseconds Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 2ms Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 1ms Nov 23 20:12:41 volumio volumio[5023]: info: ------------------------------ 2ms Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 4 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 4 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus stop Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 9ms Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 46ms Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 46ms Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 45ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 38 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 38 milliseconds Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 56ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":"(#1 - 926/1000) Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 75ms Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 4ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:12:42 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:12:42 volumio volumio[5023]: info: Nov 23 20:12:42 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 5 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 5ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 4 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 34ms Nov 23 20:12:42 volumio volumio[5023]: info: ------------------------------ 33ms Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand status took 28 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 23 20:12:42 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:12:42 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:42 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:42 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:42 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:42 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:42 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:43 volumio volumio[5023]: info: ------------------------------ 74ms Nov 23 20:12:43 volumio volumio[5023]: info: ------------------------------ 73ms Nov 23 20:12:43 volumio volumio[5023]: info: ------------------------------ 71ms Nov 23 20:12:43 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 48 milliseconds Nov 23 20:12:43 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:12:43 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:12:43 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:12:43 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:12:43 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"AK AUSSERKONTROLLE - AK AUSSERKONTROLLE - BLACKOUT (prod. MIKKY JUIC) [Official Video] 4K","artist":"Sneders","album":null,"uri":"http://5.249.160.143:8000/","trackType":"143:8000/"} Nov 23 20:12:43 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:12:43 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:12:43 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:12:43 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:12:43 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:12:43 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:43 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:12:43 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:43 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:12:43 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:12:43 volumio volumio[5023]: info: ------------------------------ 90ms Nov 23 20:12:47 volumio volumio[5023]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 23 20:12:47 volumio volumio[5023]: info: Retrying Login Due to Network Error Nov 23 20:12:57 volumio volumio[5023]: info: MyVolumio login type: Token Nov 23 20:13:11 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::ClearQueue Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::stPlaybackTimer Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::serviceStop Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::serviceStop Nov 23 20:13:11 volumio volumio[5023]: info: [1732421591168] ControllerWebradio::stop Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::clearPlayQueue Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::addQueueItems Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::addQueueItems Nov 23 20:13:11 volumio volumio[5023]: info: Preload queue cleared Nov 23 20:13:11 volumio volumio[5023]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1896614 Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::saveQueue Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::updateTrackBlock Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrackBlock Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPlay Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::play index 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::stop Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::play index undefined Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: [1732421591179] ControllerWebradio::clearAddPlayTrack Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand stop took 14 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand stop took 2 milliseconds Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:11 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:11 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand status took 5 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand clear took 4 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1896614" Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:11 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 13ms Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: error: updateQueue error: null Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand status took 13 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 12ms Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 12 milliseconds Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:11 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:11 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:11 volumio volumio[5023]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 23 20:13:11 volumio volumio[5023]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 22ms Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 26ms Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1896614" took 238 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 20:13:11 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand play Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 230ms Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 230ms Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: info: Nov 23 20:13:11 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:11 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:11 volumio volumio[5023]: info: sendMpdCommand play took 2 milliseconds Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 2ms Nov 23 20:13:11 volumio volumio[5023]: info: ------------------------------ 2ms Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 4 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 6 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 5 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 4 milliseconds Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 6 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus stop Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 23ms Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 45ms Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 45ms Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 45ms Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 38 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 37 milliseconds Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1058","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 69ms Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 70ms Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:12 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:12 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces system playlist update Nov 23 20:13:12 volumio volumio[5023]: info: Ignoring MPD Status Update Nov 23 20:13:12 volumio volumio[5023]: info: Nov 23 20:13:12 volumio volumio[5023]: ---------------------------- MPD announces state update: player Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::getState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand status Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 4ms Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 3ms Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 2 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 1ms Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand status took 3 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseState Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Williams - Tulsa Time","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Williams - Tulsa Time","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 32ms Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 31ms Nov 23 20:13:12 volumio volumio[5023]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 23 20:13:12 volumio volumio[5023]: verbose: ControllerMpd::parseTrackInfo Nov 23 20:13:12 volumio volumio[5023]: info: ControllerMpd::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::servicePushState Nov 23 20:13:12 volumio volumio[5023]: info: CorePlayQueue::getTrack 0 Nov 23 20:13:12 volumio volumio[5023]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Don Williams - Tulsa Time","artist":"113.fm Route 66","album":null,"uri":"http://75.102.53.58:80/1058","trackType":"58:80/1058"} Nov 23 20:13:12 volumio volumio[5023]: verbose: CURRENT POSITION 0 Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState stateService play Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::syncState currentStatus play Nov 23 20:13:12 volumio volumio[5023]: info: Received an update from plugin. extracting info from payload Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreStateMachine::pushState Nov 23 20:13:12 volumio volumio[5023]: info: CoreCommandRouter::volumioPushState Nov 23 20:13:12 volumio volumio[5023]: info: ------------------------------ 43ms Nov 23 20:13:13 volumio volumio[5023]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:13:13 volumio volumio[5023]: Error: socket hang up Nov 23 20:13:13 volumio volumio[5023]: at connResetException (internal/errors.js:639:14) Nov 23 20:13:13 volumio volumio[5023]: at Socket.socketOnEnd (_http_client.js:499:23) Nov 23 20:13:13 volumio volumio[5023]: at Socket.emit (events.js:412:35) Nov 23 20:13:13 volumio volumio[5023]: at endReadableNT (internal/streams/readable.js:1333:12) Nov 23 20:13:13 volumio volumio[5023]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Nov 23 20:13:13 volumio volumio[5023]: code: 'ECONNRESET' Nov 23 20:13:13 volumio volumio[5023]: } Nov 23 20:13:13 volumio volumio[5023]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:13:13 volumio sudo[5744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 20:12 Nov 23 20:13:13 volumio sudo[5744]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"