Mar 04 11:40:10 volumio go-librespot[1708]: time="2026-03-04T11:40:10-03:00" level=trace msg="sent dealer ping" Mar 04 11:40:10 volumio go-librespot[1708]: time="2026-03-04T11:40:10-03:00" level=trace msg="received dealer pong" Mar 04 11:40:30 volumio sshd[2538]: Received disconnect from 192.168.1.94 port 64502:11: disconnected by user Mar 04 11:40:30 volumio sshd[2538]: Disconnected from user volumio 192.168.1.94 port 64502 Mar 04 11:40:30 volumio sshd[2459]: pam_unix(sshd:session): session closed for user volumio Mar 04 11:40:30 volumio systemd[1]: session-1.scope: Deactivated successfully. Mar 04 11:40:30 volumio systemd-logind[739]: Session 1 logged out. Waiting for processes to exit. Mar 04 11:40:30 volumio systemd-logind[739]: Removed session 1. Mar 04 11:40:36 volumio volumio[1289]: info: CoreCommandRouter::volumioPlay Mar 04 11:40:36 volumio volumio[1289]: info: CoreStateMachine::play index undefined Mar 04 11:40:36 volumio volumio[1289]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:40:36 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:36 volumio volumio[1289]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:40:36 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:36 volumio volumio[1289]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Volare%22%2C%22artist%22%3A%22Imad%20Fares%22%2C%22album%22%3A%22The%20Best%20of%20Latin%20Dance%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FffMG8hMsdoANj55YuTxmsL61hoRQ0R3UjwHMjtoTBYpAlZAGgvi_DGk88recvDqPU29fO1MSrDDGDfI%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22rqgAIq22G10%22%2C%22playlistId%22%3A%22RDTMAK5uy_nTsa0Irmcu2li2-qHBoZxtrpG9HuC3k_Q%22%2C%22params%22%3A%228gECGAI%253D%22%2C%22playlistSetVideoId%22%3A%2256B44F6D10557CC6%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDTMAK5uy_nTsa0Irmcu2li2-qHBoZxtrpG9HuC3k_Q%22%2C%22params%22%3A%228gECGAI%253D%22%2C%22videoId%22%3A%22AESGMaqZ8WQ%22%7D%7D%7D%7D Mar 04 11:40:36 volumio volumio[1289]: info: [ytmusic] Obtained PO token for video #rqgAIq22G10: MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc Mar 04 11:40:36 volumio volumio[1289]: info: [ytmusic] (Volare) validating stream URL "https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00"... Mar 04 11:40:37 volumio volumio[1289]: info: [ytmusic] (Volare) stream validated in 0.057s. Mar 04 11:40:37 volumio volumio[1289]: info: [ytmusic] (AutoplayManager) Added volumioStateListener Mar 04 11:40:37 volumio volumio[1289]: info: [ytmusic] (AutoplayManager) Enabled Mar 04 11:40:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:40:37 volumio volumio[1289]: info: sendMpdCommand stop took 10 milliseconds Mar 04 11:40:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:40:37 volumio volumio[1289]: info: sendMpdCommand clear took 1 milliseconds Mar 04 11:40:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube" Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: sendMpdCommand addid "https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube" took 16 milliseconds Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 15ms Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 13ms Mar 04 11:40:37 volumio volumio[1289]: verbose: MPD COMMAND [object Object] Mar 04 11:40:37 volumio volumio[1289]: verbose: MPD COMMAND [object Object] Mar 04 11:40:37 volumio volumio[1289]: verbose: MPD COMMAND [object Object] Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 23ms Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 21ms Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 10ms Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 8ms Mar 04 11:40:37 volumio volumio[1289]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:40:37 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand play Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: Mar 04 11:40:37 volumio volumio[1289]: ---------------------------- MPD announces system playlist update Mar 04 11:40:37 volumio volumio[1289]: info: Ignoring MPD Status Update Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 11ms Mar 04 11:40:37 volumio volumio[1289]: info: sendMpdCommand play took 6 milliseconds Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 6ms Mar 04 11:40:37 volumio volumio[1289]: info: ------------------------------ 5ms Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 16 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: info: Mar 04 11:40:38 volumio volumio[1289]: ---------------------------- MPD announces state update: player Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::getState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 37 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 32 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 29 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 17 milliseconds Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 91ms Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand status took 47 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 36 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseState Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:40:38 volumio volumio[1289]: info: Received an update from plugin. extracting info from payload Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: [ytmusic] (AutoplayManager) Added mpdStateListener Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:40:38 volumio volumio[1289]: info: Received an update from plugin. extracting info from payload Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":386,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:40:38 volumio volumio[1289]: info: Received an update from plugin. extracting info from payload Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":996,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:40:38 volumio volumio[1289]: info: Received an update from plugin. extracting info from payload Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 339ms Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 335ms Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 335ms Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 315ms Mar 04 11:40:38 volumio volumio[1289]: info: sendMpdCommand playlistinfo took 253 milliseconds Mar 04 11:40:38 volumio volumio[1289]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:40:38 volumio volumio[1289]: info: ControllerMpd::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::servicePushState Mar 04 11:40:38 volumio volumio[1289]: info: CorePlayQueue::getTrack 0 Mar 04 11:40:38 volumio volumio[1289]: verbose: STATE SERVICE {"status":"play","position":0,"seek":996,"duration":235,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Volare","artist":"Imad Fares","album":"The Best of Latin Dance","uri":"https://rr1---sn-uxgg5-9gje.googlevideo.com/videoplayback?expire=1772656836&ei=ZESoab7dKMSMobIPjM7A8AU&ip=181.162.169.90&id=o-ACbNL09cuqAqVQ4HHjRDlBbXMfg25fN2QST7bo8FCEh5&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=350&met=1772635236%2C&mh=4W&mm=31%2C29&mn=sn-uxgg5-9gje%2Csn-uxgg5-njay&ms=au%2Crdu&mv=m&mvi=1&pl=21&rms=au%2Cau&ctier=A&pfa=5&pcm2=no&gcr=cl&initcwndbps=2662500&hightc=yes&siu=1&bui=AVNa5-wkQH52LLtJ5A3XAnM_0qXQ96406aHiwsyYo45b3831mE2qkVF10Ra-oVmgfNzqC0CTRg&spc=6dlaFNgq6gOJYlHPjkGNz58uWjB07TWKipm2tzBv1IHUiNFiFg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=VnDk9ZzVxXZRj1xr9Cafxl8T&rqh=1&gir=yes&clen=8392144&dur=235.021&lmt=1759812780482777&mt=1772634802&fvip=4&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968%2C51791334&c=WEB_REMIX&sefc=1&txp=2318224&n=5O2ThgQLeuDyGg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cpcm2%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAN3HDBuXXZNgAi0h5jS_cJiysRUxgaEUpTgaj56AZumiAiBnHfLK6d82hLY_W36vv61DGM1GswPIxcDzpAgQTGrcnw%3D%3D&sig=AHEqNM4wRgIhAJG8-9tjgqwu5_fOrJZ53wmoWOZKbUlXuYW8FlWNtERdAiEA9LhUah6AFduf3W1SHvR4e2XtZ2mg0qu7o_sxpOOOBPM%3D&pot=MlXXxRoariSEsNf7wpueBFiDAu9wovgZKdcM9w-RFPrfEJI0ovpBWYk7aemQzpX6KksBdJWmW5h653QbA4-JeNuSCeQxnAV-pKFqHaEYRAg69FOpZPwc&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Mar 04 11:40:38 volumio volumio[1289]: verbose: CURRENT POSITION 0 Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState stateService play Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:40:38 volumio volumio[1289]: info: Received an update from plugin. extracting info from payload Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreStateMachine::pushState Mar 04 11:40:38 volumio volumio[1289]: info: CoreCommandRouter::volumioPushState Mar 04 11:40:38 volumio volumio[1289]: info: ------------------------------ 345ms Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:38 volumio volumio[1289]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Mar 04 11:40:40 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Mar 04 11:40:40 volumio systemd[2511]: Activating special unit exit.target... Mar 04 11:40:40 volumio systemd[2511]: Stopped target default.target - Main User Target. Mar 04 11:40:40 volumio systemd[2511]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Mar 04 11:40:40 volumio mpris-proxy[2527]: org.bluez appeared Mar 04 11:40:40 volumio mpris-proxy[2527]: Bluetooth Adapter /org/bluez/hci0 found Mar 04 11:40:40 volumio mpris-proxy[2527]: Bluetooth Adapter /org/bluez/hci0 removed Mar 04 11:40:40 volumio mpris-proxy[2527]: org.bluez disappeared Mar 04 11:40:40 volumio systemd[2511]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Mar 04 11:40:40 volumio systemd[2511]: Stopping dbus.service - D-Bus User Message Bus... Mar 04 11:40:40 volumio systemd[2511]: Stopped dbus.service - D-Bus User Message Bus. Mar 04 11:40:40 volumio systemd[2511]: Removed slice session.slice - User Core Session Slice. Mar 04 11:40:40 volumio systemd[2511]: Stopped target basic.target - Basic System. Mar 04 11:40:40 volumio systemd[2511]: Stopped target paths.target - Paths. Mar 04 11:40:40 volumio systemd[2511]: Stopped target sockets.target - Sockets. Mar 04 11:40:40 volumio systemd[2511]: Stopped target timers.target - Timers. Mar 04 11:40:40 volumio systemd[2511]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 04 11:40:40 volumio systemd[2511]: Closed dirmngr.socket - GnuPG network certificate management daemon. Mar 04 11:40:40 volumio systemd[2511]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 04 11:40:40 volumio systemd[2511]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 04 11:40:40 volumio systemd[2511]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 04 11:40:40 volumio systemd[2511]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 04 11:40:40 volumio systemd[2511]: Removed slice app.slice - User Application Slice. Mar 04 11:40:40 volumio systemd[2511]: Reached target shutdown.target - Shutdown. Mar 04 11:40:40 volumio systemd[2511]: Finished systemd-exit.service - Exit the Session. Mar 04 11:40:40 volumio systemd[2511]: Reached target exit.target - Exit the Session. Mar 04 11:40:40 volumio (sd-pam)[2512]: pam_unix(systemd-user:session): session closed for user volumio Mar 04 11:40:40 volumio systemd[1]: user@1000.service: Deactivated successfully. Mar 04 11:40:40 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Mar 04 11:40:40 volumio systemd[1]: user@1000.service: Consumed 1.031s CPU time. Mar 04 11:40:40 volumio go-librespot[1708]: time="2026-03-04T11:40:40-03:00" level=trace msg="sent dealer ping" Mar 04 11:40:40 volumio go-librespot[1708]: time="2026-03-04T11:40:40-03:00" level=trace msg="received dealer pong" Mar 04 11:40:40 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Mar 04 11:40:40 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Mar 04 11:40:40 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Mar 04 11:40:40 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Mar 04 11:40:40 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Mar 04 11:40:40 volumio systemd[1]: user-1000.slice: Consumed 1.573s CPU time. Mar 04 11:40:51 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 11:40:51 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 04 11:40:56 volumio volumio[1289]: info: Enabling plugin touch_display Mar 04 11:40:56 volumio volumio[1289]: info: Loading plugin "touch_display"... Mar 04 11:40:56 volumio volumio[1289]: info: Applying required configuration parameters for plugin touch_display Mar 04 11:40:56 volumio volumio[1289]: info: PLUGIN START: touch_display Mar 04 11:40:56 volumio volumio[1289]: info: Loading i18n strings for locale es Mar 04 11:40:56 volumio volumio[1289]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 04 11:40:56 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 04 11:40:57 volumio sudo[3204]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Mar 04 11:40:57 volumio sudo[3204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio sudo[3204]: pam_unix(sudo:session): session closed for user root Mar 04 11:40:57 volumio sudo[3207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Mar 04 11:40:57 volumio sudo[3207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio volumio[1289]: info: touch_display: No backlight interface detected. Mar 04 11:40:57 volumio sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Mar 04 11:40:57 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio sudo[3211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 04 11:40:57 volumio sudo[3211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio sudo[3217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Mar 04 11:40:57 volumio sudo[3217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio volumio[1289]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Mar 04 11:40:57 volumio sudo[3217]: pam_unix(sudo:session): session closed for user root Mar 04 11:40:57 volumio volumio[1289]: info: touch_display: IframeKeyboardBridge extension installed successfully Mar 04 11:40:57 volumio sudo[3220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Mar 04 11:40:57 volumio sudo[3220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:40:57 volumio sudo[3220]: pam_unix(sudo:session): session closed for user root Mar 04 11:40:57 volumio volumio[1289]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Mar 04 11:40:57 volumio systemd[1]: Reloading. Mar 04 11:40:58 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 11:40:58 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 11:40:58 volumio systemd[1]: Reloading. Mar 04 11:40:58 volumio sudo[3211]: pam_unix(sudo:session): session closed for user root Mar 04 11:40:58 volumio volumio[1289]: info: touch_display: systemctl daemon-reload succeeded. Mar 04 11:40:58 volumio sudo[3259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 04 11:40:58 volumio sudo[3259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 11:41:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 04 11:41:00 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Mar 04 11:41:00 volumio volumio[1289]: info: touch_display: systemctl disable getty@tty1.service succeeded. Mar 04 11:41:00 volumio sudo[3259]: pam_unix(sudo:session): session closed for user root Mar 04 11:41:00 volumio volumio[1289]: error: touch_display: Failed to start volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start volumio-kiosk.service Mar 04 11:41:00 volumio volumio[1289]: Failed to start volumio-kiosk.service: Unit volumio-kiosk.service not found. Mar 04 11:41:00 volumio volumio[1289]: info: Error: Error Mar 04 11:41:00 volumio sudo[3207]: pam_unix(sudo:session): session closed for user root Mar 04 11:41:00 volumio volumio[1289]: info: touch_display: systemctl stop getty@tty1.service succeeded. Mar 04 11:41:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 04 11:41:04 volumio volumio[1289]: info: Received Get System Info Mar 04 11:41:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 04 11:41:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 04 11:41:04 volumio volumio[1289]: info: Discovery: Getting this device information Mar 04 11:41:04 volumio volumio[1289]: info: CoreCommandRouter::volumioGetState Mar 04 11:41:04 volumio volumio[1289]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 04 11:41:10 volumio volumio[1289]: info: Starting Uninstall of plugin user_interface - touch_display Mar 04 11:41:10 volumio volumio[1289]: info: Uninstalling plugin touch_display Mar 04 11:41:10 volumio volumio[1289]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Mar 04 11:41:10 volumio sudo[3322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Mar 04 11:41:10 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:41:10 volumio sudo[3322]: pam_unix(sudo:session): session closed for user root Mar 04 11:41:10 volumio sudo[3324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Mar 04 11:41:10 volumio volumio[1289]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Mar 04 11:41:10 volumio sudo[3324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:41:10 volumio volumio[1289]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Mar 04 11:41:10 volumio volumio[1289]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:41:10 volumio sudo[3327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Mar 04 11:41:10 volumio volumio[1289]: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 11:41:10 volumio volumio[1289]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Mar 04 11:41:10 volumio volumio[1289]: at ChildProcess.exithandler (node:child_process:413:7) Mar 04 11:41:10 volumio volumio[1289]: at ChildProcess.emit (node:events:514:28) Mar 04 11:41:10 volumio volumio[1289]: at maybeClose (node:internal/child_process:1105:16) Mar 04 11:41:10 volumio volumio[1289]: at Socket. (node:internal/child_process:457:11) Mar 04 11:41:10 volumio volumio[1289]: at Socket.emit (node:events:514:28) Mar 04 11:41:10 volumio volumio[1289]: at Pipe. (node:net:337:12) Mar 04 11:41:10 volumio sudo[3327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:41:10 volumio volumio[1289]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:41:10 volumio sudo[3330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Mar 04 11:41:10 volumio go-librespot[1708]: time="2026-03-04T11:41:10-03:00" level=trace msg="sent dealer ping" Mar 04 11:41:10 volumio sudo[3330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 04 11:41:10 volumio sudo[3330]: pam_unix(sudo:session): session closed for user root Mar 04 11:41:10 volumio systemd[1]: Reloading. Mar 04 11:41:10 volumio go-librespot[1708]: time="2026-03-04T11:41:10-03:00" level=trace msg="received dealer pong" Mar 04 11:41:11 volumio sudo[3365]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 11:40' Mar 04 11:41:11 volumio sudo[3365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"