-- Logs begin at Mon 2025-11-03 12:41:09 CET, end at Mon 2025-11-03 13:24:14 CET. -- Nov 03 13:23:00 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5OaqffbeQyVVqtnA71Ci7K","service":"spop","name":"Por la Raja de Tu Falda","artist":"Estopa","album":"Estopa","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b2731a311570340a4f6530da158b","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:00 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30ddpolNHwXlrS5XO8MiiO","service":"spop","name":"Marta, Sebas, Guille y los demás","artist":"Amaral","album":"Pájaros En La Cabeza","type":"song","duration":223,"albumart":"https://i.scdn.co/image/ab67616d0000b2736cad2d8f144a2bdfe43d9052","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:05 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3Th2of8xIYyImVLXUbzaTp","service":"spop","name":"Tenía Tanto Que Darte","artist":"Nena Daconte","album":"Retales De Carnaval","type":"song","duration":204,"albumart":"https://i.scdn.co/image/ab67616d0000b2734d610f100fc60fbe4f84420e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:05 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1EC0JRoSi9YL5zExd3Euc3","service":"spop","name":"No Puedo Vivir Sin Ti","artist":"Los Ronaldos","album":"4 Canciones","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b2734963e4821aed03e3e90a3e63","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:08 volumio sudo[7671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 13:23:08 volumio sudo[7671]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 13:23:08 volumio sudo[7671]: pam_unix(sudo:session): session closed for user root Nov 03 13:23:08 volumio sudo[7673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 13:23:08 volumio sudo[7673]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 13:23:08 volumio sudo[7673]: pam_unix(sudo:session): session closed for user root Nov 03 13:23:08 volumio volumio[1031]: verbose: New Socket.io Connection to 10.0.0.113 from 10.0.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:144.0) Gecko/20100101 Firefox/144.0 Engine version: 3 Transport: polling Total Clients: 5 Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::volumioGetVisibleSources Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:09 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::volumioGetQueue Nov 03 13:23:09 volumio volumio[1031]: info: CoreStateMachine::getQueue Nov 03 13:23:09 volumio volumio[1031]: info: CorePlayQueue::getQueue Nov 03 13:23:09 volumio volumio[1031]: info: Listing playlists Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 03 13:23:09 volumio volumio[1031]: info: Received Get System Info Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 13:23:09 volumio volumio[1031]: info: Discovery: Getting this device information Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:09 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:09 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 03 13:23:09 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 13:23:10 volumio volumio[1031]: info: Received Get System Info Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 13:23:10 volumio volumio[1031]: info: Discovery: Getting this device information Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:10 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 13:23:10 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1hbWtHgRDG25DkmxE4I28V","service":"spop","name":"Puedes Contar Conmigo","artist":"La Oreja de Van Gogh","album":"Lo Que Te Conte Mientras Te Hacias La Dormida","type":"song","duration":236,"albumart":"https://i.scdn.co/image/ab67616d0000b273820f6e4afa04594a671e484f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:10 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1msphInQ8bBBQGFZkll7FX","service":"spop","name":"En Que Estrella Estara","artist":"Nena Daconte","album":"He Perdido Los Zapatos","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b273892c520bbb97002f21ffdd62","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:10 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 03 13:23:11 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 13:23:11 volumio volumio[1031]: info: In handleBrowseUri, curUri=spotify/playlists Nov 03 13:23:15 volumio go-librespot[7078]: time="2025-11-03T13:23:15+01:00" level=trace msg="sent dealer ping" Nov 03 13:23:15 volumio go-librespot[7078]: time="2025-11-03T13:23:15+01:00" level=trace msg="received dealer pong" Nov 03 13:23:15 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:79eUc9cUUoUOO1b5znphiU","service":"spop","name":"Física o química","artist":"Despistaos","album":"Lo que hemos vivido","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b27304f762d58f314cf70072c4c2","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:15 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:24UStI5AYA7g5ufFDh3LH0","service":"spop","name":"Por la boca vive el pez","artist":"Fito y Fitipaldis","album":"Por la boca vive el pez","type":"song","duration":269,"albumart":"https://i.scdn.co/image/ab67616d0000b273c78c3bfdd0f4b88b9dc2fcb8","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:16 volumio go-librespot[7078]: time="2025-11-03T13:23:16+01:00" level=debug msg="fetched chunk 5/6, size: 524288" uri="spotify:track:6V4ntlX6608rd3Ec5SpVhj" Nov 03 13:23:18 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 03 13:23:20 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:129lYDVKnWtlJc2PZJviuA","service":"spop","name":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b27323a1613112c1ecf946f51177","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:20 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:53AafWSzYsiJKnAtJTUB5f","service":"spop","name":"La Tortura (feat. Alejandro Sanz)","artist":"Shakira","album":"Fijación Oral Volumen 1 (Expanded Edition)","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273afccde0243b5c750c07f6825","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:23 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 13:23:23 volumio volumio[1031]: info: Received Get System Info Nov 03 13:23:23 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 13:23:23 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 13:23:23 volumio volumio[1031]: info: Discovery: Getting this device information Nov 03 13:23:23 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:23 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:23 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 13:23:25 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:62sv8pbKqGwVeN4F20DSXM","service":"spop","name":"Un violinista en tu tejado","artist":"Melendi","album":"Curiosa La Cara De Tu Padre","type":"song","duration":222,"albumart":"https://i.scdn.co/image/ab67616d0000b2733f39425dd684788ae6edb372","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:25 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2LsbizbOeNa4x6qsi2jAMb","service":"spop","name":"La flaca","artist":"Jarabe De Palo","album":"La Flaca","type":"song","duration":261,"albumart":"https://i.scdn.co/image/ab67616d0000b27347a83522c634e84f937a897e","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 13:23:26 volumio volumio[1031]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 03 13:23:26 volumio volumio[1031]: info: Received Get System Version Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 13:23:26 volumio volumio[1031]: info: Received Get System Info Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 03 13:23:26 volumio volumio[1031]: info: Discovery: Getting this device information Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:23:26 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:26 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 03 13:23:30 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:19CmuECYssqkPWANF4nLWM","service":"spop","name":"Para No Verte Más","artist":"La Mosca","album":"Visperas De Carnaval","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b27360fc79eea7acba5248619f82","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:30 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3b1IQflSLrgzYQPGFzI9cl","service":"spop","name":"Es Por Ti","artist":"Juanes","album":"Un Día Normal","type":"song","duration":250,"albumart":"https://i.scdn.co/image/ab67616d0000b27323a1613112c1ecf946f51177","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:33 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 03 13:23:33 volumio volumio[1031]: info: CURURI: playlists Nov 03 13:23:33 volumio volumio[1031]: info: Listing playlists Nov 03 13:23:33 volumio volumio[1031]: info: Preload queue cleared Nov 03 13:23:34 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 13:23:34 volumio volumio[1031]: info: In handleBrowseUri, curUri=spotify Nov 03 13:23:34 volumio volumio[1031]: info: Preload queue cleared Nov 03 13:23:34 volumio volumio[1031]: info: Preload queue cleared Nov 03 13:23:34 volumio volumio[1031]: info: Preload queue cleared Nov 03 13:23:34 volumio volumio[1031]: info: Preload queue cleared Nov 03 13:23:35 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0oXLSuno5adlHcPzlzs8l6","service":"spop","name":"Duele El Amor","artist":"Aleks Syntek","album":"Mundo Lite","type":"song","duration":275,"albumart":"https://i.scdn.co/image/ab67616d0000b2732ff95cfe681839038a35b115","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:35 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:02dphTJYUQ9pmdNC52iyOz","service":"spop","name":"Fiesta Pagana","artist":"Mägo de Oz","album":"Finisterra","type":"song","duration":296,"albumart":"https://i.scdn.co/image/ab67616d0000b2733e782d96dc4cbe7950a274a9","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:37 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 13:23:37 volumio volumio[1031]: info: In handleBrowseUri, curUri=spotify/mytracks Nov 03 13:23:40 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3TY1PFZXmYeiLcXygB74My","service":"spop","name":"Suerte (Whenever, Wherever)","artist":"Shakira","album":"Laundry Service","type":"song","duration":194,"albumart":"https://i.scdn.co/image/ab67616d0000b2731f400a1f4d821b00824cf58f","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:40 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5FiB1uNoGZE4PenzZd7Imu","service":"spop","name":"Bonito","artist":"Jarabe De Palo","album":"Bonito","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b2734c60ea841024590b90908755","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:45 volumio go-librespot[7078]: time="2025-11-03T13:23:45+01:00" level=debug msg="fetched chunk 6/6, size: 235907" uri="spotify:track:6V4ntlX6608rd3Ec5SpVhj" Nov 03 13:23:45 volumio go-librespot[7078]: time="2025-11-03T13:23:45+01:00" level=trace msg="sent dealer ping" Nov 03 13:23:45 volumio go-librespot[7078]: time="2025-11-03T13:23:45+01:00" level=trace msg="received dealer pong" Nov 03 13:23:45 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:100Tm6z29RfHTdUQ6NIs0s","service":"spop","name":"Me Voy","artist":"Julieta Venegas","album":"Limon Y Sal","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273a043fa84be801dca33dacca1","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:45 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2EM9zpAc7PVeoAydmbfVIL","service":"spop","name":"La Camisa Negra","artist":"Juanes","album":"Mi Sangre","type":"song","duration":216,"albumart":"https://i.scdn.co/image/ab67616d0000b273c091c40987e92a9256d2f210","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=debug msg="handling pause player command from 1ff9a9aeeda7e7dfc3913b02abe6ed2bc1c5a28d" Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=debug msg="pause track at 85975ms" Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=debug msg="sending successful reply for dealer request" Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:23:49 volumio go-librespot[7078]: time="2025-11-03T13:23:49+01:00" level=trace msg="emitting websocket event: paused" Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","play_origin":"go-librespot"}} Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: {"status":"pause","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:49 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:49 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:49 volumio volumio[1031]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:49 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::syncState stateService pause Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:49 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:49 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 13:23:49 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","play_origin":"go-librespot"}} Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: {"status":"pause","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:49 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:49 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:49 volumio volumio[1031]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:49 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::syncState stateService pause Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:49 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:49 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:49 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:49 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=debug msg="handling resume player command from 1ff9a9aeeda7e7dfc3913b02abe6ed2bc1c5a28d" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=trace msg="seek to 85975ms (diff: 57ms, samples: 3791497, bytes: 1643654)" uri="spotify:track:6V4ntlX6608rd3Ec5SpVhj" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+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, PCM format = FLOAT_LE" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=debug msg="resume track at 85918ms" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=trace msg="scheduling prefetch in 63s" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=debug msg="sending successful reply for dealer request" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:23:50 volumio go-librespot[7078]: time="2025-11-03T13:23:50+01:00" level=trace msg="emitting websocket event: playing" Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","resume":true,"play_origin":"go-librespot"}} Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:50 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","resume":true,"play_origin":"go-librespot"}} Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:50 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0tIpUUbOYIWqvI50Ksf7D5","service":"spop","name":"Donde Estan las Gatas","artist":"Alex Gargolas","album":"Gargolas 3","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273e67cb23316fb372fb739209c","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:50 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Berghain","artist":"ROSALÍA, Björk, Yves Tumor","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d00001e0208256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","seek":85000,"duration":178,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:23:50 volumio volumio[1031]: verbose: CURRENT POSITION 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState stateService play Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::syncState currentStatus play Nov 03 13:23:50 volumio volumio[1031]: info: Received an update from plugin. extracting info from payload Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:23:50 volumio volumio[1031]: info: CorePlayQueue::getTrack 0 Nov 03 13:23:50 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:23:50 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:30XvfCUPHSK2rzxmPlJGFf","service":"spop","name":"Loba","artist":"Shakira","album":"Loba","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b273a866322cb8bd99270372df74","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:55 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:65H6t1WQBim6q93yM8fEwn","service":"spop","name":"Obsesion","artist":"Aventura","album":"We Broke The Rules","type":"song","duration":254,"albumart":"https://i.scdn.co/image/ab67616d0000b273e1afa83bd73aa80c0ff0ee34","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:55 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4mTsRdFE28MYlrZo8N4Es0","service":"spop","name":"La Costa Del Silencio","artist":"Mägo de Oz","album":"Gaia","type":"song","duration":280,"albumart":"https://i.scdn.co/image/ab67616d0000b273b637449c74ec8501ed2e03aa","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:23:59 volumio go-librespot[7078]: time="2025-11-03T13:23:59+01:00" level=debug msg="handling play player command from 1ff9a9aeeda7e7dfc3913b02abe6ed2bc1c5a28d" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DZ06evO0Qv0E8" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="fetched new page 0 with 43 items (list: 43)" uri="spotify:playlist:37i9dQZF1DZ06evO0Qv0E8" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="emitting websocket event: will_play" Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO0Qv0E8","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","play_origin":"playlist"}} Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO0Qv0E8","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","play_origin":"playlist"}} Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="selected format OGG_VORBIS_160 (4072040c84e331ace35b69a7234592b952e1852c)" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="requested aes key for file 4072040c84e331ace35b69a7234592b952e1852c, gid: 6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="fetched first chunk of 8, total size is 3998120 bytes" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="seek to 1ms (diff: -267ms, samples: 44, bytes: 0)" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=info msg="loaded track \"Querer Querernos\" (paused: false, position: 1ms, duration: 240047ms, prefetched: false)" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="fetched chunk 1/7, size: 524288" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="scheduling prefetch in 210s" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="emitting websocket event: metadata" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="sending successful reply for dealer request" Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","name":"Querer Querernos","artist_names":["Canserbero"],"album_name":"Querer Querernos","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","position":1,"duration":240047,"release_date":"year:2011 month:9 day:16","track_number":1,"disc_number":1}} Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","name":"Querer Querernos","artist_names":["Canserbero"],"album_name":"Querer Querernos","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","position":1,"duration":240047,"release_date":"year:2011 month:9 day:16","track_number":1,"disc_number":1}} Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=trace msg="emitting websocket event: playing" Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO0Qv0E8","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","resume":false,"play_origin":"playlist"}} Nov 03 13:24:00 volumio volumio[1031]: info: Spotify is playing in volatile mode Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: UNSET VOLATILE Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","position":0,"title":"Berghain","artist":"ROSALÍA","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d0000b27308256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","codec":"ogg","seek":85000,"duration":178,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":94,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 03 13:24:00 volumio volumio[1031]: info: Setting Spotify stop after unset volatile call Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="handling update_context player command from b64a0fb02b9e2118674b716e353a7932e01802b0" Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="fetched chunk 2/7, size: 524288" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO0Qv0E8","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","resume":false,"play_origin":"playlist"}} Nov 03 13:24:00 volumio volumio[1031]: info: Spotify is playing in volatile mode Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: UNSET VOLATILE Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","position":0,"title":"Berghain","artist":"ROSALÍA","album":"Berghain","albumart":"https://i.scdn.co/image/ab67616d0000b27308256748d3e6c3ed016cab16","uri":"spotify:track:6V4ntlX6608rd3Ec5SpVhj","trackType":"spotify","codec":"ogg","seek":85000,"duration":178,"samplerate":"160 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":true,"repeatSingle":false,"consume":false,"volume":94,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Nov 03 13:24:00 volumio volumio[1031]: info: Setting Spotify stop after unset volatile call Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="sending successful reply for dealer request" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="handling update_context player command from b64a0fb02b9e2118674b716e353a7932e01802b0" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="fetched chunk 3/7, size: 524288" uri="spotify:track:6d3q0F9VNtdxQUTVlRcet6" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 13:24:00 volumio go-librespot[7078]: time="2025-11-03T13:24:00+01:00" level=debug msg="sending successful reply for dealer request" Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7dITAq1YP5e0kTcaDq4YWI","service":"spop","name":"Limón y Sal","artist":"Julieta Venegas","album":"Limon Y Sal","type":"song","duration":205,"albumart":"https://i.scdn.co/image/ab67616d0000b273a043fa84be801dca33dacca1","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: {"status":"play","service":"spop","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::servicePushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreStateMachine::pushState Nov 03 13:24:00 volumio volumio[1031]: info: CoreCommandRouter::volumioPushState Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: RECEIVED VOLUMIO VOLUME 94 Nov 03 13:24:00 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6b37xrsNCWYIUphFBazqD6","service":"spop","name":"Me Gustas Tu","artist":"Manu Chao","album":"Próxima Estación: Esperanza","type":"song","duration":239,"albumart":"https://i.scdn.co/image/ab67616d0000b2731dcba4a728ca0b17cbd204a5","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:01 volumio volumio[1031]: info: Spotify Stop Nov 03 13:24:01 volumio volumio[1031]: SPOTIFY: SPOTIFY STOP Nov 03 13:24:01 volumio volumio[1031]: SPOTIFY: {"status":"play","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","codec":"ogg","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":94,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Nov 03 13:24:01 volumio volumio[1031]: info: Spotify Stop Nov 03 13:24:01 volumio volumio[1031]: SPOTIFY: SPOTIFY STOP Nov 03 13:24:01 volumio volumio[1031]: SPOTIFY: {"status":"play","title":"Querer Querernos","artist":"Canserbero","album":"Querer Querernos","albumart":"https://i.scdn.co/image/ab67616d00001e02b67647d776568c8aa90c889d","uri":"spotify:track:6d3q0F9VNtdxQUTVlRcet6","trackType":"spotify","codec":"ogg","seek":1001,"duration":240,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":94,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Nov 03 13:24:05 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3UI9I3e1g1y5T3SMfdgfGO","service":"spop","name":"Colgando en tus manos (con Marta Sánchez)","artist":"Carlos Baute","album":"De mi puño y letra - Edicion Coleccionista","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b2731f91da617b2e5527d58d2453","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:05 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ri9fLG89OdSpjxheYxU7S","service":"spop","name":"Me Muero","artist":"La Quinta Estacion","album":"El Mundo Se Equivoca","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b27321af408fba4ba3aa98d8c606","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:08 volumio volumio[1031]: info: CoreCommandRouter::volumioGetState Nov 03 13:24:10 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2es7AohVgF03LwjmQyaVMl","service":"spop","name":"Torero","artist":"Chayanne","album":"Chayanne","type":"song","duration":218,"albumart":"https://i.scdn.co/image/ab67616d0000b273ae6fa77d018a876139391901","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:10 volumio volumio[1031]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2DNyZP4Py6f4zMASLBnIu6","service":"spop","name":"La Playa","artist":"La Oreja de Van Gogh","album":"El Viaje de Copperpot","type":"song","duration":247,"albumart":"https://i.scdn.co/image/ab67616d0000b273fb01108d92974ffab07a05fb","samplerate":"160 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 13:24:13 volumio volumio[1031]: info: [1762172653149] [80s80s] Pushing the next song state Michael Jackson - Billie Jean and getting next track. Nov 03 13:24:13 volumio volumio[1031]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 13:24:13 volumio volumio[1031]: TypeError: Cannot set property 'name' of undefined Nov 03 13:24:13 volumio volumio[1031]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Nov 03 13:24:13 volumio volumio[1031]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Nov 03 13:24:13 volumio volumio[1031]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Nov 03 13:24:13 volumio volumio[1031]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Nov 03 13:24:13 volumio volumio[1031]: at processImmediate (internal/timers.js:461:21) Nov 03 13:24:13 volumio volumio[1031]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 13:24:14 volumio sudo[7837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 13:23 Nov 03 13:24:14 volumio sudo[7837]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"