-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-02-23 14:42:10 CET. -- Feb 23 14:41:05 denon volumio[1178]: info: Tunnel connection is inactive, restarting it Feb 23 14:41:05 denon volumio[1178]: info: Starting Tunnel 1 Feb 23 14:41:05 denon volumio[1178]: info: Starting Tunnel Connection Checker Feb 23 14:41:05 denon sudo[2514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 23 14:41:05 denon sudo[2514]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 14:41:05 denon autossh[1940]: received signal to exit (15) Feb 23 14:41:05 denon systemd[1]: Stopping MyVolumio SSH Tunnel... Feb 23 14:41:05 denon systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Feb 23 14:41:05 denon systemd[1]: sshtunnel.service: Succeeded. Feb 23 14:41:05 denon systemd[1]: Stopped MyVolumio SSH Tunnel. Feb 23 14:41:05 denon volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 23 14:41:05 denon volumio[1178]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 23 14:41:05 denon systemd[1]: Started MyVolumio SSH Tunnel. Feb 23 14:41:05 denon sudo[2514]: pam_unix(sudo:session): session closed for user root Feb 23 14:41:05 denon autossh[2517]: port set to 0, monitoring disabled Feb 23 14:41:05 denon autossh[2517]: starting ssh (count 1) Feb 23 14:41:05 denon autossh[2517]: ssh child pid is 2520 Feb 23 14:41:05 denon volumio[1178]: info: Remote SSH Started Feb 23 14:41:05 denon volumio[1178]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 23 14:41:05 denon volumio[1178]: info: CoreCommandRouter::volumioGetState Feb 23 14:41:05 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:41:06 denon go-librespot[1437]: time="2025-02-23T14:41:06+01:00" level=debug msg="fetched chunk 9/10, size: 524288" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 23 14:41:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 14:41:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 14:41:08 denon volumio[1178]: info: Discovery: Getting this device information Feb 23 14:41:08 denon volumio[1178]: info: CoreCommandRouter::volumioGetState Feb 23 14:41:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:41:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 14:41:10 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:10 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:15 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:15 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:17 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:17 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:20 denon go-librespot[1437]: time="2025-02-23T14:41:20+01:00" level=debug msg="fetched chunk 10/10, size: 449540" uri="spotify:track:6pb5DtlAeG95dNQLHiArRU" Feb 23 14:41:24 denon volumiossh-tunnel[2516]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Feb 23 14:41:24 denon go-librespot[1437]: time="2025-02-23T14:41:24+01:00" level=trace msg="sent dealer ping" Feb 23 14:41:24 denon go-librespot[1437]: time="2025-02-23T14:41:24+01:00" level=trace msg="received dealer pong" Feb 23 14:41:26 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:26 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:26 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:41:26 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: spop , goto Feb 23 14:41:27 denon volumio[1178]: info: Executing endpoint getSimilarAlbums Feb 23 14:41:27 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 23 14:41:27 denon volumio[1178]: info: Executing endpoint getSimilarAlbums Feb 23 14:41:27 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Feb 23 14:41:28 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:28 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:28 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:28 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:28 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:28 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:28 denon volumio[1178]: info: Executing endpoint metavolumio Feb 23 14:41:28 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 23 14:41:37 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 23 14:41:37 denon volumio[1178]: info: In handleBrowseUri, curUri=spotify Feb 23 14:41:37 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:37 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:37 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:37 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:38 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 14:41:38 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 14:41:38 denon volumio[1178]: info: Discovery: Getting this device information Feb 23 14:41:38 denon volumio[1178]: info: CoreCommandRouter::volumioGetState Feb 23 14:41:38 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:41:38 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 14:41:41 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 23 14:41:41 denon volumio[1178]: info: In handleBrowseUri, curUri=spotify/playlists Feb 23 14:41:41 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:52 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 23 14:41:52 denon volumio[1178]: info: In handleBrowseUri, curUri=spotify Feb 23 14:41:52 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:52 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:52 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:52 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:54 denon go-librespot[1437]: time="2025-02-23T14:41:54+01:00" level=trace msg="sent dealer ping" Feb 23 14:41:54 denon go-librespot[1437]: time="2025-02-23T14:41:54+01:00" level=trace msg="received dealer pong" Feb 23 14:41:55 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Feb 23 14:41:55 denon volumio[1178]: info: In handleBrowseUri, curUri=spotify/mytoptracks Feb 23 14:41:55 denon volumio[1178]: info: Preload queue cleared Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0kdLrg7jLzNcfTgL0cdwoN Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:6c1tRwIOxWNN4FCbFDnrlA Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:6h3EEYgtIuOjeqQPIC3H37 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:77X5UMAX4FjUx1aOaLqotz Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:5QUl8GnrWCzruxJQizLc41 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1pQH4slBU9Wet9DXFcogsd Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:76KNnAocccoTjptMvbquZ2 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0U29CCc7QjzPRdfiWMFzrT Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:5WuMAqIhUfDqfCSBNqD8uz Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:44ky35XkE8koPNYpJVXoog Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:4kzGsOHg2qAxfLV89PGmcM Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:24z7Kd42Crj9CDxDToezOW Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0IoOhPY9bb3zlNxEj95au1 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0oqTVQ6rofB92RGR8xqjK1 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:11iy0hHims4pB3sQl6dQBz Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:13NtHliC7rYdFppxoh93aJ Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1IJuY4BUv0FcIs6yhJkHfP Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1QxOHmL2r4RFH9BgAmw8GK Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1RWeLLR6CUZqtb0heVAFss Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:20kR9TsywOPljH2N1qAFDn Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:27ZEr3LW65VzLeBHuvtkdC Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:2FySuEtCxca0aLA8SMWdm2 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:2IhU5vTMAco8Ohv6WehI6F Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:2sVxZ4NtDGfXGl7BOZ3epq Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:2yAHQqZDrmLXikHmT1Ym6t Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:3HDON6vRZcvnskkDumfSvj Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:3JZTxsUGB5vARGgfBUpfBa Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:3SHPewlxeafiJAcEwHxhFX Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:3SiwkCpPBJuoLiY46bF28d Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:3pJXKzkC8JsxC3UATeM5nN Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:5c1sW6mG2DD7p10Cgfz6tG Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:5qf59XnWEnTZBFbdIuJ9L0 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:7KOZLQFIFeR9sfDqoo00Dr Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:7a6UACmsdi1MPd1KN7ct8I Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1hutMFyL0C62Cq73E2HrWR Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:1xHbeaBnk5tV1BEInmVKlw Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:2NnUQnOlDEWv4Uv1P21xb8 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:4lBbiT5FK7J5ZuKGXO1jvh Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:6fDU7UWoIHPsRRCQgeCNhb Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:7Mtq1nIu6tTU4jFCnaxPfX Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:33M1qDVLA1EkQkF1qeBCCI Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:03Q3SlCVD0bbuClaMXO2Ht Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:05bGR4IRgwfsyYqEsdBMPK Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:064k2gdNNZBoAHKionWQ54 Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0EU7Q3ofsPaKBrF0EEWy8O Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0F8zqM99Jiq0aSr2hXYOXx Feb 23 14:41:55 denon volumio[1178]: info: Preloading song: spotify:track:0H4M2SFQOKaSzN1iMuz56M Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:0kdLrg7jLzNcfTgL0cdwoN in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0kdLrg7jLzNcfTgL0cdwoN Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:6c1tRwIOxWNN4FCbFDnrlA in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:6c1tRwIOxWNN4FCbFDnrlA Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:6h3EEYgtIuOjeqQPIC3H37 in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:6h3EEYgtIuOjeqQPIC3H37 Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6c1tRwIOxWNN4FCbFDnrlA","service":"spop","name":"Verdade","artist":"Karinah","album":"Verdade","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b2737be9903fe71468ab8a381a02","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:77X5UMAX4FjUx1aOaLqotz in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:77X5UMAX4FjUx1aOaLqotz Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0kdLrg7jLzNcfTgL0cdwoN","service":"spop","name":"Universo","artist":"Manu","album":"Universo","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b2734a0d39096c41a918568324b2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:77X5UMAX4FjUx1aOaLqotz","service":"spop","name":"PARECE - Ao Vivo","artist":"Luan Santana","album":"LUAN AO VIVO NA LUA - CRESCENTE","type":"song","duration":144,"albumart":"https://i.scdn.co/image/ab67616d0000b27372f5ede1b025cf73249c83ce","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6h3EEYgtIuOjeqQPIC3H37","service":"spop","name":"Pilantra e Meio","artist":"Eric Land","album":"Ao Vivo em São Paulo","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ee8b7f59c70be365d0c9273","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:5QUl8GnrWCzruxJQizLc41 in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:5QUl8GnrWCzruxJQizLc41 Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:1pQH4slBU9Wet9DXFcogsd in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1pQH4slBU9Wet9DXFcogsd Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn","service":"spop","name":"Mentirosa - Ao Vivo","artist":"Hugo & Guilherme","album":"Mentirosa (Ao Vivo)","type":"song","duration":165,"albumart":"https://i.scdn.co/image/ab67616d0000b273e295ee75598a12d933800298","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5QUl8GnrWCzruxJQizLc41","service":"spop","name":"Não Conto pra Ninguém - Ao Vivo","artist":"Kadu Di Castro","album":"Dvd Nova História (Ao Vivo)","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b2731bba49afb68db67cc4141a6a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:76KNnAocccoTjptMvbquZ2 in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:76KNnAocccoTjptMvbquZ2 Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:0U29CCc7QjzPRdfiWMFzrT in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0U29CCc7QjzPRdfiWMFzrT Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1pQH4slBU9Wet9DXFcogsd","service":"spop","name":"Swędzi Mnie Dziura","artist":"Cypis","album":"Swędzi Mnie Dziura","type":"song","duration":182,"albumart":"https://i.scdn.co/image/ab67616d0000b273054ad964f77243beb508a090","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:5WuMAqIhUfDqfCSBNqD8uz in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:5WuMAqIhUfDqfCSBNqD8uz Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:76KNnAocccoTjptMvbquZ2","service":"spop","name":"Bebecita","artist":"Hellfield","album":"NARCOPOLO","type":"song","duration":139,"albumart":"https://i.scdn.co/image/ab67616d0000b273ffd6f4cebc27501a9b130d12","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","service":"spop","name":"TEMPERATURA","artist":"Skolim","album":"Król Latino","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:44ky35XkE8koPNYpJVXoog in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:44ky35XkE8koPNYpJVXoog Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WuMAqIhUfDqfCSBNqD8uz","service":"spop","name":"Let It Snow! Let It Snow! Let It Snow!","artist":"Jonathan Snow","album":"Magical Christmas Album","type":"song","duration":87,"albumart":"https://i.scdn.co/image/ab67616d0000b273873d77fe01f423a8bf17430b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:4kzGsOHg2qAxfLV89PGmcM in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:4kzGsOHg2qAxfLV89PGmcM Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:44ky35XkE8koPNYpJVXoog","service":"spop","name":"Nie Dzwoń do Mnie Mała","artist":"Skolim","album":"Król Latino","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:24z7Kd42Crj9CDxDToezOW in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:24z7Kd42Crj9CDxDToezOW Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4kzGsOHg2qAxfLV89PGmcM","service":"spop","name":"Happy Holiday","artist":"Percy Faith & His Orchestra & Chorus","album":"Christmas Is...","type":"song","duration":118,"albumart":"https://i.scdn.co/image/ab67616d0000b273eed39f50d5455e4b15806a33","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:0IoOhPY9bb3zlNxEj95au1 in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0IoOhPY9bb3zlNxEj95au1 Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:0oqTVQ6rofB92RGR8xqjK1 in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0oqTVQ6rofB92RGR8xqjK1 Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24z7Kd42Crj9CDxDToezOW","service":"spop","name":"Wyglądasz Idealnie","artist":"Skolim","album":"Król Latino","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:11iy0hHims4pB3sQl6dQBz in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:11iy0hHims4pB3sQl6dQBz Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0IoOhPY9bb3zlNxEj95au1","service":"spop","name":"Sleigh Ride","artist":"Larry Groce","album":"Disney's Family Christmas Collection","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b273c037d5f417df8cdc9772815b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:13NtHliC7rYdFppxoh93aJ in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:13NtHliC7rYdFppxoh93aJ Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0oqTVQ6rofB92RGR8xqjK1","service":"spop","name":"Co w tobie jest","artist":"Skolim","album":"Król Latino","type":"song","duration":147,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:1IJuY4BUv0FcIs6yhJkHfP in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1IJuY4BUv0FcIs6yhJkHfP Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:11iy0hHims4pB3sQl6dQBz","service":"spop","name":"Dziewczyno Piękna","artist":"Skolim","album":"Król Latino","type":"song","duration":145,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13NtHliC7rYdFppxoh93aJ","service":"spop","name":"For Auld Lang Syne (Auld Lang Syne)","artist":"The Christmas Guys","album":"What's That Christmas Song","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273313753d3ee9a842be20ec0e5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:1QxOHmL2r4RFH9BgAmw8GK in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1QxOHmL2r4RFH9BgAmw8GK Feb 23 14:41:56 denon volumio[1178]: info: Exploding uri spotify:track:1RWeLLR6CUZqtb0heVAFss in service spop Feb 23 14:41:56 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1RWeLLR6CUZqtb0heVAFss Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1IJuY4BUv0FcIs6yhJkHfP","service":"spop","name":"Baby, It's Cold Outside","artist":"Christmas 2019","album":"Festive Christmas Covers","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273028b6baf49642e2a2e6b8df2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QxOHmL2r4RFH9BgAmw8GK","service":"spop","name":"My Favorite Things","artist":"Carpenters","album":"Christmas Collection","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b273772f4769270cca469da19801","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:20kR9TsywOPljH2N1qAFDn in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:20kR9TsywOPljH2N1qAFDn Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:27ZEr3LW65VzLeBHuvtkdC in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:27ZEr3LW65VzLeBHuvtkdC Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1RWeLLR6CUZqtb0heVAFss","service":"spop","name":"Jingle Bell Rock","artist":"Santa Ana Players","album":"Christmas Party Music","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b2731b49850e5438233c37e02931","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:20kR9TsywOPljH2N1qAFDn","service":"spop","name":"The Most Wonderful Day Of The Year - Instrumental","artist":"Decca Concert Orchestra","album":"Rudolph The Red-Nosed Reindeer","type":"song","duration":146,"albumart":"https://i.scdn.co/image/ab67616d0000b273edcc2355b480d00f8aad8422","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:2FySuEtCxca0aLA8SMWdm2 in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:2FySuEtCxca0aLA8SMWdm2 Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:2IhU5vTMAco8Ohv6WehI6F in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:2IhU5vTMAco8Ohv6WehI6F Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:27ZEr3LW65VzLeBHuvtkdC","service":"spop","name":"JEDEN TANIEC JEDNA NOC","artist":"MiłyPan","album":"JEDEN TANIEC JEDNA NOC","type":"song","duration":259,"albumart":"https://i.scdn.co/image/ab67616d0000b273e6ea7bb48d9c81979588fa59","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FySuEtCxca0aLA8SMWdm2","service":"spop","name":"Holly Jolly Christmas","artist":"Walter Weeman's Brass & Singers","album":"Weihnachten zu Hause: A Swinging Christmas","type":"song","duration":119,"albumart":"https://i.scdn.co/image/ab67616d0000b273565dba600b8c5cd50f40c30b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:2sVxZ4NtDGfXGl7BOZ3epq in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:2sVxZ4NtDGfXGl7BOZ3epq Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:2yAHQqZDrmLXikHmT1Ym6t in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:2yAHQqZDrmLXikHmT1Ym6t Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2IhU5vTMAco8Ohv6WehI6F","service":"spop","name":"You're a Mean One, Mr. Grinch","artist":"Choralbeatpeople","album":"Cuddling Christmas","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b273b45900f60e31f448eaa2dde5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:3HDON6vRZcvnskkDumfSvj in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:3HDON6vRZcvnskkDumfSvj Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2sVxZ4NtDGfXGl7BOZ3epq","service":"spop","name":"Sleigh Ride","artist":"Swing Sisters","album":"Swingin' X-Mas","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7b192de1dd809d9363e090c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2yAHQqZDrmLXikHmT1Ym6t","service":"spop","name":"Rockin' Around the Christmas Tree","artist":"Christmas 2019","album":"Christmas Classics - Christmas Holiday Music","type":"song","duration":123,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cd2c87ffaa80aab88de3fcc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:3JZTxsUGB5vARGgfBUpfBa in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:3JZTxsUGB5vARGgfBUpfBa Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:3SHPewlxeafiJAcEwHxhFX in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:3SHPewlxeafiJAcEwHxhFX Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3HDON6vRZcvnskkDumfSvj","service":"spop","name":"Drama","artist":"Magdalena Narożna","album":"Drama","type":"song","duration":198,"albumart":"https://i.scdn.co/image/ab67616d0000b2735810ddd77a10bf049e87226f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3JZTxsUGB5vARGgfBUpfBa","service":"spop","name":"Polar Pals","artist":"Christmas Music For Kids","album":"Christmas Angels","type":"song","duration":106,"albumart":"https://i.scdn.co/image/ab67616d0000b273c20521d60637d87a90513d20","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:3SiwkCpPBJuoLiY46bF28d in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:3SiwkCpPBJuoLiY46bF28d Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:3pJXKzkC8JsxC3UATeM5nN in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:3pJXKzkC8JsxC3UATeM5nN Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SHPewlxeafiJAcEwHxhFX","service":"spop","name":"Have a Holly Jolly Christmas","artist":"Johnny Mathis","album":"The Christmas Album","type":"song","duration":119,"albumart":"https://i.scdn.co/image/ab67616d0000b273000acdd2296901c5b9cab58b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3SiwkCpPBJuoLiY46bF28d","service":"spop","name":"Kiss me Baby","artist":"Skolim","album":"Król Latino","type":"song","duration":155,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:5c1sW6mG2DD7p10Cgfz6tG in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:5c1sW6mG2DD7p10Cgfz6tG Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3pJXKzkC8JsxC3UATeM5nN","service":"spop","name":"Wonderful Christmas Time","artist":"Cailean McLean","album":"Have Yourself a Merry Little Christmas","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273d3fe5dcb707b12c63b0961d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:5qf59XnWEnTZBFbdIuJ9L0 in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:5qf59XnWEnTZBFbdIuJ9L0 Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5c1sW6mG2DD7p10Cgfz6tG","service":"spop","name":"Rudolph, The Red-Nosed Reindeer","artist":"Sammy Kaye and His Orchestra","album":"I Want to Wish You a Merry Christmas","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b273b58b079e534d83feb01dbd1c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:7KOZLQFIFeR9sfDqoo00Dr in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:7KOZLQFIFeR9sfDqoo00Dr Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5qf59XnWEnTZBFbdIuJ9L0","service":"spop","name":"Rudolph the Red Nosed Reindeer","artist":"Countdown Nashville Singers","album":"Christmas at Home: A Country Christmas","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2739af112d3e47dd5d7c7e1f32d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:7a6UACmsdi1MPd1KN7ct8I in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:7a6UACmsdi1MPd1KN7ct8I Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7KOZLQFIFeR9sfDqoo00Dr","service":"spop","name":"O Holy Night - 1959 Version","artist":"Percy Faith & His Orchestra","album":"The Music of Christmas (Expanded Edition)","type":"song","duration":221,"albumart":"https://i.scdn.co/image/ab67616d0000b273e27586a75a1671a5da5a3301","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:1hutMFyL0C62Cq73E2HrWR in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1hutMFyL0C62Cq73E2HrWR Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:1xHbeaBnk5tV1BEInmVKlw in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:1xHbeaBnk5tV1BEInmVKlw Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7a6UACmsdi1MPd1KN7ct8I","service":"spop","name":"There's No Place Like Home for the Holidays","artist":"Christmas 2019","album":"Christmas Classics - Christmas Holiday Music","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cd2c87ffaa80aab88de3fcc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:2NnUQnOlDEWv4Uv1P21xb8 in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:2NnUQnOlDEWv4Uv1P21xb8 Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1hutMFyL0C62Cq73E2HrWR","service":"spop","name":"Coco Jambo - remix","artist":"Dj Bony Remix","album":"Coco Jambo (remix)","type":"song","duration":137,"albumart":"https://i.scdn.co/image/ab67616d0000b273b4aeccc21565874304513a65","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1xHbeaBnk5tV1BEInmVKlw","service":"spop","name":"Erotyczne Pif-Paf","artist":"Figo i Samogony","album":"Erotyczne Pif-Paf","type":"song","duration":175,"albumart":"https://i.scdn.co/image/ab67616d0000b2737571c65ef8221137e0723b88","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:4lBbiT5FK7J5ZuKGXO1jvh in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:4lBbiT5FK7J5ZuKGXO1jvh Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2NnUQnOlDEWv4Uv1P21xb8","service":"spop","name":"Are You Ready (We Love the 90S) - Radio Edit","artist":"Masterboy","album":"Are You Ready (We Love the 90S) - The Edits","type":"song","duration":218,"albumart":"https://i.scdn.co/image/ab67616d0000b273912b6c4d54b0dc23f8ffcd0f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:57 denon volumio[1178]: info: Exploding uri spotify:track:6fDU7UWoIHPsRRCQgeCNhb in service spop Feb 23 14:41:57 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:6fDU7UWoIHPsRRCQgeCNhb Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4lBbiT5FK7J5ZuKGXO1jvh","service":"spop","name":"Kto wkurzy Cię jak ja","artist":"Topky","album":"Kto wkurzy Cię jak ja","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273174f8e2e9cf240e6c445a9ae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:7Mtq1nIu6tTU4jFCnaxPfX in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:7Mtq1nIu6tTU4jFCnaxPfX Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6fDU7UWoIHPsRRCQgeCNhb","service":"spop","name":"Ona Mi Dała","artist":"Skolim","album":"Król Latino","type":"song","duration":164,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:33M1qDVLA1EkQkF1qeBCCI in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:33M1qDVLA1EkQkF1qeBCCI Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Mtq1nIu6tTU4jFCnaxPfX","service":"spop","name":"What Is Love - 12\" Mix","artist":"Haddaway","album":"What Is Love","type":"song","duration":402,"albumart":"https://i.scdn.co/image/ab67616d0000b2739c783e96159db6857816809e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:03Q3SlCVD0bbuClaMXO2Ht in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:03Q3SlCVD0bbuClaMXO2Ht Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:33M1qDVLA1EkQkF1qeBCCI","service":"spop","name":"Palermo","artist":"Skolim","album":"Król Latino","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b273737a478649c6d76fa00cf6eb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:05bGR4IRgwfsyYqEsdBMPK in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:05bGR4IRgwfsyYqEsdBMPK Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:064k2gdNNZBoAHKionWQ54 in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:064k2gdNNZBoAHKionWQ54 Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:03Q3SlCVD0bbuClaMXO2Ht","service":"spop","name":"Frosty The Snow Man","artist":"Gene Autry","album":"Gene Autry: A Melody Ranch Christmas","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273dc34057850fd3c300594240b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:05bGR4IRgwfsyYqEsdBMPK","service":"spop","name":"Let It Snow Let It Snow Let It Snow","artist":"Christmas 2019","album":"Christmas Classics - Christmas Holiday Music","type":"song","duration":117,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cd2c87ffaa80aab88de3fcc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:0EU7Q3ofsPaKBrF0EEWy8O in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0EU7Q3ofsPaKBrF0EEWy8O Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:064k2gdNNZBoAHKionWQ54","service":"spop","name":"The Holly and the Ivy","artist":"The O'Neill Brothers Group","album":"Merry Christmas: Holiday Piano Collection","type":"song","duration":246,"albumart":"https://i.scdn.co/image/ab67616d0000b2732a08bac5331da107148c9fdb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:0F8zqM99Jiq0aSr2hXYOXx in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0F8zqM99Jiq0aSr2hXYOXx Feb 23 14:41:58 denon volumio[1178]: info: Exploding uri spotify:track:0H4M2SFQOKaSzN1iMuz56M in service spop Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: EXPLODING URI:spotify:track:0H4M2SFQOKaSzN1iMuz56M Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0EU7Q3ofsPaKBrF0EEWy8O","service":"spop","name":"The 12 Days of Christmas","artist":"The Jolly Players","album":"Spirit of Christmas Music","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273dad58bd00ba0b3b423771aaa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0F8zqM99Jiq0aSr2hXYOXx","service":"spop","name":"Ona lubi pomarańcze","artist":"After Party","album":"Ona lubi pomarańcze","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b2738d61475703fb0716de5a2851","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:41:58 denon volumio[1178]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0H4M2SFQOKaSzN1iMuz56M","service":"spop","name":"Toyland - Remastered April 1989","artist":"Perry Como","album":"I Wish It Could Be Christmas Forever","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b273053fc4b54c2901bb91e01229","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Feb 23 14:42:00 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:00 denon volumio[1178]: info: CorePlayQueue::getTrack 1 Feb 23 14:42:07 denon go-librespot[1437]: time="2025-02-23T14:42:07+01:00" level=trace msg="emitting websocket event: not_playing" Feb 23 14:42:07 denon go-librespot[1437]: time="2025-02-23T14:42:07+01:00" level=debug msg="resolving autoplay station for 1 tracks" Feb 23 14:42:07 denon volumio[1178]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:6pb5DtlAeG95dNQLHiArRU","play_origin":"go-librespot"}} Feb 23 14:42:07 denon volumio[1178]: error: Failed to decode event: not_playing Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="resolved autoplay station: spotify:station:track:6pb5DtlAeG95dNQLHiArRU" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="resolved context of track" uri="spotify:station:track:6pb5DtlAeG95dNQLHiArRU" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:track:6pb5DtlAeG95dNQLHiArRU" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=trace msg="emitting websocket event: will_play" Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","play_origin":"go-librespot"}} Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="selected format OGG_VORBIS_320 (316785cb685f8ff1b542152a87620426f2fed531)" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="requested aes key for file 316785cb685f8ff1b542152a87620426f2fed531, gid: 0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="fetched first chunk of 16, total size is 8157712 bytes" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=info msg="loaded track \"Stumblin' In\" (paused: false, position: 0ms, duration: 213363ms, prefetched: false)" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon volumio[1178]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 23 14:42:08 denon volumio[1178]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 23 14:42:08 denon volumio[1178]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 23 14:42:08 denon volumio[1178]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 14:42:08 denon volumio[1178]: info: Discovery: Getting this device information Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::volumioGetState Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=trace msg="scheduling prefetch in 183s" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=trace msg="emitting websocket event: metadata" Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","name":"Stumblin' In","artist_names":["CYRIL"],"album_name":"Stumblin' In","album_cover_url":"https://i.scdn.co/image/ab67616d00001e029a903819d8a5d3c80c47734c","position":0,"duration":213363,"release_date":"year:2023 month:11 day:10","track_number":1,"disc_number":1}} Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:0h3Xy4V4apMraB5NuM8U7Z" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:08 denon go-librespot[1437]: time="2025-02-23T14:42:08+01:00" level=trace msg="emitting websocket event: playing" Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","play_origin":"go-librespot"}} Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: PUSH STATE SPOTIFY Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: {"status":"play","service":"spop","title":"Stumblin' In","artist":"CYRIL","album":"Stumblin' In","albumart":"https://i.scdn.co/image/ab67616d00001e029a903819d8a5d3c80c47734c","uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::servicePushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Stumblin' In","artist":"CYRIL","album":"Stumblin' In","albumart":"https://i.scdn.co/image/ab67616d00001e029a903819d8a5d3c80c47734c","uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","trackType":"spotify","seek":0,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:08 denon volumio[1178]: verbose: CURRENT POSITION 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::syncState stateService play Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::syncState currentStatus play Feb 23 14:42:08 denon volumio[1178]: info: Received an update from plugin. extracting info from payload Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:08 denon volumio[1178]: info: [ASDebug] CurState: play PrevState: play Feb 23 14:42:08 denon volumio[1178]: info: [ASDebug] CurState: play PrevState: play Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:08 denon kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 23 14:42:08 denon volumio[1178]: info: camilladsp spawned new process with pid 2677, instance 1, run: true Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: PUSH STATE SPOTIFY Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: {"status":"play","service":"spop","title":"Stumblin' In","artist":"CYRIL","album":"Stumblin' In","albumart":"https://i.scdn.co/image/ab67616d00001e029a903819d8a5d3c80c47734c","uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::servicePushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Stumblin' In","artist":"CYRIL","album":"Stumblin' In","albumart":"https://i.scdn.co/image/ab67616d00001e029a903819d8a5d3c80c47734c","uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","trackType":"spotify","seek":1000,"duration":213,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:08 denon volumio[1178]: verbose: CURRENT POSITION 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::syncState stateService play Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::syncState currentStatus play Feb 23 14:42:08 denon volumio[1178]: info: Received an update from plugin. extracting info from payload Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:08 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:08 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:08 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:08 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:08 denon volumio[1178]: info: [ASDebug] CurState: play PrevState: play Feb 23 14:42:08 denon volumio[1178]: info: [ASDebug] CurState: play PrevState: play Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:08 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:09 denon volumio[1178]: info: Preload queue cleared Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::ClearQueue Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::stop Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::stPlaybackTimer Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::updateTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:09 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:09 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::serviceStop Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 0 Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::serviceStop Feb 23 14:42:09 denon volumio[1178]: info: Spotify Stop Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: SPOTIFY STOP Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: {"status":"play","position":0,"title":"Rock My Body","artist":"R3HAB","album":"Rock My Body (with INNA & Sash!)","albumart":"https://i.scdn.co/image/ab67616d0000b27368eb565abb15fc77286076f8","uri":"spotify:track:6pb5DtlAeG95dNQLHiArRU","trackType":"spotify","codec":"ogg","seek":140984,"duration":137,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":72,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 23 14:42:09 denon volumio[1178]: info: Sending Spotify command to local API: /player/pause Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::clearPlayQueue Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::saveQueue Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPushQueue Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::addQueueItems Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::addQueueItems Feb 23 14:42:09 denon volumio[1178]: info: Preload queue cleared Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0kdLrg7jLzNcfTgL0cdwoN Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0kdLrg7jLzNcfTgL0cdwoN Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:6c1tRwIOxWNN4FCbFDnrlA Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:6c1tRwIOxWNN4FCbFDnrlA Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:6h3EEYgtIuOjeqQPIC3H37 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:6h3EEYgtIuOjeqQPIC3H37 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:77X5UMAX4FjUx1aOaLqotz Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:77X5UMAX4FjUx1aOaLqotz Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:7pBPXkaVQaP3Y3Wwfkpvbn Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:5QUl8GnrWCzruxJQizLc41 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:5QUl8GnrWCzruxJQizLc41 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1pQH4slBU9Wet9DXFcogsd Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1pQH4slBU9Wet9DXFcogsd Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:76KNnAocccoTjptMvbquZ2 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:76KNnAocccoTjptMvbquZ2 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0U29CCc7QjzPRdfiWMFzrT Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0U29CCc7QjzPRdfiWMFzrT Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPushQueue Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::saveQueue Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::updateTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPlay Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::play index 8 Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::addQueueItems Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::addQueueItems Feb 23 14:42:09 denon volumio[1178]: info: Preload queue cleared Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:5WuMAqIhUfDqfCSBNqD8uz Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:5WuMAqIhUfDqfCSBNqD8uz Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:44ky35XkE8koPNYpJVXoog Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:44ky35XkE8koPNYpJVXoog Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:4kzGsOHg2qAxfLV89PGmcM Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:4kzGsOHg2qAxfLV89PGmcM Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:24z7Kd42Crj9CDxDToezOW Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:24z7Kd42Crj9CDxDToezOW Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0IoOhPY9bb3zlNxEj95au1 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0IoOhPY9bb3zlNxEj95au1 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0oqTVQ6rofB92RGR8xqjK1 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0oqTVQ6rofB92RGR8xqjK1 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:11iy0hHims4pB3sQl6dQBz Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:11iy0hHims4pB3sQl6dQBz Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:13NtHliC7rYdFppxoh93aJ Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:13NtHliC7rYdFppxoh93aJ Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1IJuY4BUv0FcIs6yhJkHfP Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1IJuY4BUv0FcIs6yhJkHfP Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1QxOHmL2r4RFH9BgAmw8GK Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1QxOHmL2r4RFH9BgAmw8GK Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1RWeLLR6CUZqtb0heVAFss Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1RWeLLR6CUZqtb0heVAFss Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:20kR9TsywOPljH2N1qAFDn Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:20kR9TsywOPljH2N1qAFDn Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:27ZEr3LW65VzLeBHuvtkdC Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:27ZEr3LW65VzLeBHuvtkdC Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:2FySuEtCxca0aLA8SMWdm2 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:2FySuEtCxca0aLA8SMWdm2 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:2IhU5vTMAco8Ohv6WehI6F Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:2IhU5vTMAco8Ohv6WehI6F Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:2sVxZ4NtDGfXGl7BOZ3epq Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:2sVxZ4NtDGfXGl7BOZ3epq Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:2yAHQqZDrmLXikHmT1Ym6t Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:2yAHQqZDrmLXikHmT1Ym6t Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:3HDON6vRZcvnskkDumfSvj Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:3HDON6vRZcvnskkDumfSvj Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:3JZTxsUGB5vARGgfBUpfBa Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:3JZTxsUGB5vARGgfBUpfBa Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:3SHPewlxeafiJAcEwHxhFX Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:3SHPewlxeafiJAcEwHxhFX Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:3SiwkCpPBJuoLiY46bF28d Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:3SiwkCpPBJuoLiY46bF28d Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:3pJXKzkC8JsxC3UATeM5nN Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:3pJXKzkC8JsxC3UATeM5nN Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:5c1sW6mG2DD7p10Cgfz6tG Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:5c1sW6mG2DD7p10Cgfz6tG Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:5qf59XnWEnTZBFbdIuJ9L0 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:5qf59XnWEnTZBFbdIuJ9L0 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:7KOZLQFIFeR9sfDqoo00Dr Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:7KOZLQFIFeR9sfDqoo00Dr Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:7a6UACmsdi1MPd1KN7ct8I Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:7a6UACmsdi1MPd1KN7ct8I Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1hutMFyL0C62Cq73E2HrWR Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1hutMFyL0C62Cq73E2HrWR Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:1xHbeaBnk5tV1BEInmVKlw Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:1xHbeaBnk5tV1BEInmVKlw Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:2NnUQnOlDEWv4Uv1P21xb8 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:2NnUQnOlDEWv4Uv1P21xb8 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:4lBbiT5FK7J5ZuKGXO1jvh Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:4lBbiT5FK7J5ZuKGXO1jvh Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:6fDU7UWoIHPsRRCQgeCNhb Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:6fDU7UWoIHPsRRCQgeCNhb Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:7Mtq1nIu6tTU4jFCnaxPfX Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:7Mtq1nIu6tTU4jFCnaxPfX Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:33M1qDVLA1EkQkF1qeBCCI Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:33M1qDVLA1EkQkF1qeBCCI Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:03Q3SlCVD0bbuClaMXO2Ht Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:03Q3SlCVD0bbuClaMXO2Ht Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:05bGR4IRgwfsyYqEsdBMPK Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:05bGR4IRgwfsyYqEsdBMPK Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:064k2gdNNZBoAHKionWQ54 Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:064k2gdNNZBoAHKionWQ54 Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0EU7Q3ofsPaKBrF0EEWy8O Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0EU7Q3ofsPaKBrF0EEWy8O Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0F8zqM99Jiq0aSr2hXYOXx Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0F8zqM99Jiq0aSr2hXYOXx Feb 23 14:42:09 denon volumio[1178]: info: Adding Item to queue: spotify:track:0H4M2SFQOKaSzN1iMuz56M Feb 23 14:42:09 denon volumio[1178]: info: Using cached record of: spotify:track:0H4M2SFQOKaSzN1iMuz56M Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::stop Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPushQueue Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::saveQueue Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::play index undefined Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::updateTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrackBlock Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 8 Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::startPlaybackTimer Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 8 Feb 23 14:42:09 denon volumio[1178]: info: [1740318129224] ControllerSpotify::clearAddPlayTrack Feb 23 14:42:09 denon volumio[1178]: info: Sending Spotify command with payload to local API: /player/play Feb 23 14:42:09 denon volumio[1178]: info: [ASDebug] CurState: stop PrevState: play Feb 23 14:42:09 denon volumio[1178]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="pause track at 1510ms" Feb 23 14:42:09 denon volumio[1178]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="emitting websocket event: paused" Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:0h3Xy4V4apMraB5NuM8U7Z","play_origin":"go-librespot"}} Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: PUSH STATE SPOTIFY Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::servicePushState Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 8 Feb 23 14:42:09 denon volumio[1178]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:09 denon volumio[1178]: verbose: CURRENT POSITION 8 Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::syncState stateService pause Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::syncState currentStatus stop Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::pushState Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 8 Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::volumioPushState Feb 23 14:42:09 denon volumio[1178]: info: MRS: Pushing multiroomSync output update for this device Feb 23 14:42:09 denon volumio[1178]: info: MRS: Pushing multiroomSync output Feb 23 14:42:09 denon volumio[1178]: info: [ASDebug] CurState: stop PrevState: play Feb 23 14:42:09 denon volumio[1178]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="resolved context of track" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="emitting websocket event: will_play" Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","play_origin":"go-librespot"}} Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="selected format OGG_VORBIS_320 (ae657c1cbba9e140abb1cb35b6d3a6fe107eb932)" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="requested aes key for file ae657c1cbba9e140abb1cb35b6d3a6fe107eb932, gid: 0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="fetched first chunk of 17, total size is 8480337 bytes" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=info msg="loaded track \"TEMPERATURA\" (paused: false, position: 0ms, duration: 187637ms, prefetched: false)" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 23 14:42:09 denon volumio[1178]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="fetched chunk 3/16, size: 524288" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="scheduling prefetch in 157s" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="emitting websocket event: metadata" Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","name":"TEMPERATURA","artist_names":["Skolim"],"album_name":"Król Latino","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02737a478649c6d76fa00cf6eb","position":0,"duration":187637,"release_date":"year:2024 month:10 day:9","track_number":3,"disc_number":1}} Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="fetched chunk 1/16, size: 524288" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="fetched chunk 2/16, size: 524288" uri="spotify:track:0U29CCc7QjzPRdfiWMFzrT" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 23 14:42:09 denon go-librespot[1437]: time="2025-02-23T14:42:09+01:00" level=trace msg="emitting websocket event: playing" Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","play_origin":"go-librespot"}} Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: PUSH STATE SPOTIFY Feb 23 14:42:09 denon volumio[1178]: SPOTIFY: {"status":"play","service":"spop","title":"TEMPERATURA","artist":"Skolim","album":"Król Latino","albumart":"https://i.scdn.co/image/ab67616d00001e02737a478649c6d76fa00cf6eb","uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","trackType":"spotify","seek":0,"duration":187,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:09 denon volumio[1178]: info: CoreCommandRouter::servicePushState Feb 23 14:42:09 denon volumio[1178]: info: CorePlayQueue::getTrack 8 Feb 23 14:42:09 denon volumio[1178]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"TEMPERATURA","artist":"Skolim","album":"Król Latino","albumart":"https://i.scdn.co/image/ab67616d00001e02737a478649c6d76fa00cf6eb","uri":"spotify:track:0U29CCc7QjzPRdfiWMFzrT","trackType":"spotify","seek":0,"duration":187,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Feb 23 14:42:09 denon volumio[1178]: verbose: CURRENT POSITION 8 Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::syncState stateService play Feb 23 14:42:09 denon volumio[1178]: info: CoreStateMachine::syncState currentStatus stop Feb 23 14:42:10 denon kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Feb 23 14:42:10 denon volumio[1178]: info: camilladsp spawned new process with pid 2692, instance 1, run: true Feb 23 14:42:10 denon volumio[1178]: info: [ASDebug] Togle GPIO: OFF Feb 23 14:42:10 denon volumio[1178]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 14:42:10 denon volumio[1178]: Error: ESPIPE: invalid seek, write Feb 23 14:42:10 denon volumio[1178]: at Object.writeSync (fs.js:711:3) Feb 23 14:42:10 denon volumio[1178]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 23 14:42:10 denon volumio[1178]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 23 14:42:10 denon volumio[1178]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 23 14:42:10 denon volumio[1178]: at listOnTimeout (internal/timers.js:554:17) Feb 23 14:42:10 denon volumio[1178]: at processTimers (internal/timers.js:497:7) { Feb 23 14:42:10 denon volumio[1178]: errno: -29, Feb 23 14:42:10 denon volumio[1178]: syscall: 'write', Feb 23 14:42:10 denon volumio[1178]: code: 'ESPIPE' Feb 23 14:42:10 denon volumio[1178]: } Feb 23 14:42:10 denon volumio[1178]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 14:42:10 denon sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 14:41 Feb 23 14:42:10 denon sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"