-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-07-14 12:04:48 CEST. --
Jul 14 12:03:04 volumiopi3 volumio[1067]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 3
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Prefetching next song
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: [ytmusic] Preferred format is Opus
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube"
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand addid "https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube" took 8 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: MPD COMMAND [object Object]
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: MPD COMMAND [object Object]
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: MPD COMMAND [object Object]
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 20ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 11ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 10ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 8ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: [ytmusic-play] Prefetched and added track to MPD queue: A Dios Le Pido
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand consume 1
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: options
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 14ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand consume 1 took 9 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 10ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 9ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 8ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: options
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: options
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:22 volumiopi3 volumio[1067]: info:
Jul 14 12:03:22 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: options
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand status took 12 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand status took 7 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand status took 6 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand status took 3 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 10 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 5 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 5 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234865,"duration":238,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fotografía (con Nelly Furtado)","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qk.googlevideo.com/videoplayback?expire=1720972767&ei=f6GTZvPfDs3Op-oPnvu9sAg&ip=90.171.233.248&id=o-ABw0eI0U-3DIFf1d8ze1APsfNKhK1FNUfEwCQR8U24a2&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=OL&mm=31%2C26&mn=sn-w511uxa-h5qk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=21&gcr=es&initcwndbps=2423750&bui=AXc671KLTZeEs2fWHgnDvrBq-7NULwkea1R1jKoS1KDovCgePnYiGWko6WFNeZvxJuijf1LLIflK2VdZ&spc=NO7bAQ0R7oK-xaFBH9IunvLibtGPvaZOBTtEXTodLEUN8ZxeLQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=hEFsm8G4DLn16-cwNik1GIMQ&rqh=1&gir=yes&clen=4282844&dur=238.141&lmt=1714856840822822&mt=1720950785&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=zoG72g2V1jCGjw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgKVo3va5ksEC0boSfQs1rJuTI4AVEfavSv-9Lw8iiT6cCICHQCtRpTcUJqDh3kjJLu8gOyn8fCkLmE34ZP3trdmVs&sig=AJfQdSswRgIhANOZCtryQvu59a-xQeoV9j_R6FcgRwQbgW9ayd5kXTUkAiEAvhZeLePYWDuy2BEazecGKeU30Lqrwh6wdCpye1OzuBc%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234865,"duration":238,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fotografía (con Nelly Furtado)","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qk.googlevideo.com/videoplayback?expire=1720972767&ei=f6GTZvPfDs3Op-oPnvu9sAg&ip=90.171.233.248&id=o-ABw0eI0U-3DIFf1d8ze1APsfNKhK1FNUfEwCQR8U24a2&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=OL&mm=31%2C26&mn=sn-w511uxa-h5qk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=21&gcr=es&initcwndbps=2423750&bui=AXc671KLTZeEs2fWHgnDvrBq-7NULwkea1R1jKoS1KDovCgePnYiGWko6WFNeZvxJuijf1LLIflK2VdZ&spc=NO7bAQ0R7oK-xaFBH9IunvLibtGPvaZOBTtEXTodLEUN8ZxeLQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=hEFsm8G4DLn16-cwNik1GIMQ&rqh=1&gir=yes&clen=4282844&dur=238.141&lmt=1714856840822822&mt=1720950785&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=zoG72g2V1jCGjw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgKVo3va5ksEC0boSfQs1rJuTI4AVEfavSv-9Lw8iiT6cCICHQCtRpTcUJqDh3kjJLu8gOyn8fCkLmE34ZP3trdmVs&sig=AJfQdSswRgIhANOZCtryQvu59a-xQeoV9j_R6FcgRwQbgW9ayd5kXTUkAiEAvhZeLePYWDuy2BEazecGKeU30Lqrwh6wdCpye1OzuBc%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234865,"duration":238,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fotografía (con Nelly Furtado)","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qk.googlevideo.com/videoplayback?expire=1720972767&ei=f6GTZvPfDs3Op-oPnvu9sAg&ip=90.171.233.248&id=o-ABw0eI0U-3DIFf1d8ze1APsfNKhK1FNUfEwCQR8U24a2&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=OL&mm=31%2C26&mn=sn-w511uxa-h5qk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=21&gcr=es&initcwndbps=2423750&bui=AXc671KLTZeEs2fWHgnDvrBq-7NULwkea1R1jKoS1KDovCgePnYiGWko6WFNeZvxJuijf1LLIflK2VdZ&spc=NO7bAQ0R7oK-xaFBH9IunvLibtGPvaZOBTtEXTodLEUN8ZxeLQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=hEFsm8G4DLn16-cwNik1GIMQ&rqh=1&gir=yes&clen=4282844&dur=238.141&lmt=1714856840822822&mt=1720950785&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=zoG72g2V1jCGjw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgKVo3va5ksEC0boSfQs1rJuTI4AVEfavSv-9Lw8iiT6cCICHQCtRpTcUJqDh3kjJLu8gOyn8fCkLmE34ZP3trdmVs&sig=AJfQdSswRgIhANOZCtryQvu59a-xQeoV9j_R6FcgRwQbgW9ayd5kXTUkAiEAvhZeLePYWDuy2BEazecGKeU30Lqrwh6wdCpye1OzuBc%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":234865,"duration":238,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fotografía (con Nelly Furtado)","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qk.googlevideo.com/videoplayback?expire=1720972767&ei=f6GTZvPfDs3Op-oPnvu9sAg&ip=90.171.233.248&id=o-ABw0eI0U-3DIFf1d8ze1APsfNKhK1FNUfEwCQR8U24a2&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=OL&mm=31%2C26&mn=sn-w511uxa-h5qk%2Csn-5hne6nz6&ms=au%2Conr&mv=m&mvi=3&pl=21&gcr=es&initcwndbps=2423750&bui=AXc671KLTZeEs2fWHgnDvrBq-7NULwkea1R1jKoS1KDovCgePnYiGWko6WFNeZvxJuijf1LLIflK2VdZ&spc=NO7bAQ0R7oK-xaFBH9IunvLibtGPvaZOBTtEXTodLEUN8ZxeLQ&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=hEFsm8G4DLn16-cwNik1GIMQ&rqh=1&gir=yes&clen=4282844&dur=238.141&lmt=1714856840822822&mt=1720950785&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=zoG72g2V1jCGjw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgKVo3va5ksEC0boSfQs1rJuTI4AVEfavSv-9Lw8iiT6cCICHQCtRpTcUJqDh3kjJLu8gOyn8fCkLmE34ZP3trdmVs&sig=AJfQdSswRgIhANOZCtryQvu59a-xQeoV9j_R6FcgRwQbgW9ayd5kXTUkAiEAvhZeLePYWDuy2BEazecGKeU30Lqrwh6wdCpye1OzuBc%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:22 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 80ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 73ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 71ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: ------------------------------ 70ms
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:22 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: player
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: player
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: player
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces system playlist update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Ignoring MPD Status Update
Jul 14 12:03:26 volumiopi3 volumio[1067]: info:
Jul 14 12:03:26 volumiopi3 volumio[1067]: ---------------------------- MPD announces state update: player
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 16ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand status took 14 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 14ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand status took 13 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 12ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand status took 11 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 10ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand status took 9 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 4 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 3 milliseconds
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":95,"duration":205,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":95,"duration":205,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":95,"duration":205,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":95,"duration":205,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:26 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 2
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 65ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 63ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 61ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: ------------------------------ 59ms
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:26 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CoreStateMachine::startPlaybackTimer
Jul 14 12:03:26 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 3
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 3
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: ControllerMpd::getState
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand status
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: sendMpdCommand status took 9 milliseconds
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseState
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: sendMpdCommand playlistinfo took 2 milliseconds
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: ControllerMpd::parseTrackInfo
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: ControllerMpd::pushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::servicePushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CorePlayQueue::getTrack 3
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1219,"duration":205,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Dios Le Pido","artist":"Juanes","album":"Un Día Normal","uri":"https://rr3---sn-w511uxa-h5qd.googlevideo.com/videoplayback?expire=1720973002&ei=aqKTZuLSCvmahcIPgJKlaA&ip=90.171.233.248&id=o-AFsq6joFWLbERkW4ZJIZkIIhqkgRcuG3XI_TtVLZDCfz&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kR&mm=31%2C26&mn=sn-w511uxa-h5qd%2Csn-5hne6n6l&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=21&gcr=es&initcwndbps=2668750&bui=AXc671JI_mzee2vXPY65LJ_ia2MxybPjcWbHjRJHyXxafSiPF4B0twiTJXIxKDFbim1JvLZYv7GraS7G&spc=NO7bAa6-d1SRIb1_7DbUnzKRKxOWw8jKRohPHY0-nDruNuVW2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=xz8gmRGBdnQaM7EFj8fT-n8Q&rqh=1&gir=yes&clen=3499238&dur=205.381&lmt=1714879641827443&mt=1720951025&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5532434&n=oM1Lw4ta8-Q8bg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIgLWQqWAz37v_4hYNleTJEr1f0v3v_mqfskU04kCz-R4sCIQCdVuDDHtcBQCaD-aBoca5pQWbMaOMy3qZyT7u4e8if_w%3D%3D&sig=AJfQdSswRAIgFhN7_a7WyhbHFQ21jdWLw0F1ag3n5XPg_8D92MVttzsCIFiNqw4Wkz5rSEsLWTkZaVK_NTGyjpv3y9Qsm9JnRcgf&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"}
Jul 14 12:03:27 volumiopi3 volumio[1067]: verbose: CURRENT POSITION 3
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState stateService play
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreStateMachine::syncState currentStatus play
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: Received an update from plugin. extracting info from payload
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreStateMachine::pushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: CoreCommandRouter::volumioPushState
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:27 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:27 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - volume level for loudness 64 gain applied 0.00
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Loudness is ON true
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - Nulleq applied
Jul 14 12:03:27 volumiopi3 volumio[1067]: SPOTIFY: RECEIVED VOLUMIO VOLUME 64
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:27 volumiopi3 volumio[1067]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Jul 14 12:03:29 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 14 12:03:29 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Starting Uninstall of plugin system_controller - crossfader_switch
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Uninstalling plugin crossfader_switch
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Disabling plugin crossfader_switch
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Checking if uninstall.sh is present
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Executing uninstall.sh
Jul 14 12:03:38 volumiopi3 sudo[4936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/crossfader_switch/uninstall.sh
Jul 14 12:03:38 volumiopi3 sudo[4936]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 14 12:03:38 volumiopi3 sudo[4936]: pam_unix(sudo:session): session closed for user root
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Uninstall script completed
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Removing plugin crossfader_switch from configuration
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Successfully removed crossfader_switch configuration files
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Plugin folders cleanup
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning into folder /volumio/app/plugins/
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category audio_interface
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category miscellanea
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category music_service
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category plugins.json
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category system_controller
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category user_interface
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning into folder /data/plugins/
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category audio_interface
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category music_service
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category system_controller
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Cleaning folder for crossfader_switch
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category system_hardware
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Scanning category user_interface
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: Plugin folders cleanup completed
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.53","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"80s80s Radio","name":"80s80s","category":"music_service","version":"1.0.4","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"miniDLNA","name":"minidlna","category":"music_service","version":"3.1.12","icon":"fa-share-alt","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MotherEarthRadio","name":"motherearthradio","category":"music_service","version":"1.2.2","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.12","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.0.5","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.6","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.0.7","icon":"fa-youtube","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.1.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-spectrum","name":"peppyspectrum","category":"user_interface","version":"0.0.26","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.4.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":false,"active":false}]
Jul 14 12:03:38 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:03:44 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 14 12:03:44 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:04:05 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:04:13 volumiopi3 volumio[1067]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf
Jul 14 12:04:14 volumiopi3 volumio[1067]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/ampswitch/volumio/buster/armhf
Jul 14 12:04:14 volumiopi3 volumio[1067]: info: Folder /tmp/plugins removed
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Check plugin dependencies
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Checking if plugin already exists
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Rename folder
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Folder /tmp/downloaded_plugin.zip removed
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Move to category
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Checking if install.sh is present
Jul 14 12:04:15 volumiopi3 volumio[1067]: info: Executing install.sh
Jul 14 12:04:16 volumiopi3 sudo[5001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh
Jul 14 12:04:16 volumiopi3 sudo[5001]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 14 12:04:16 volumiopi3 volumio[1067]: Plugin install end detected on script
Jul 14 12:04:16 volumiopi3 sudo[5001]: pam_unix(sudo:session): session closed for user root
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Install script completed
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Adding reference to registry
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Done installing plugin.
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Folder /tmp/plugins removed
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Folder /tmp/downloaded_plugin.zip removed
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: Folder /data/temp removed
Jul 14 12:04:16 volumiopi3 volumio[1067]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 14 12:04:17 volumiopi3 volumio[1067]: info: Enabling plugin ampswitch
Jul 14 12:04:17 volumiopi3 volumio[1067]: info: Loading plugin "ampswitch"...
Jul 14 12:04:17 volumiopi3 volumio[1067]: info: PLUGIN START: ampswitch
Jul 14 12:04:17 volumiopi3 volumio[1067]: info: Error: Error: EBUSY: resource busy or locked, write
Jul 14 12:04:17 volumiopi3 volumio[1067]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Jul 14 12:04:29 volumiopi3 volumio[1067]: info: Enabling plugin ampswitch
Jul 14 12:04:29 volumiopi3 volumio[1067]: info: Loading plugin "ampswitch"...
Jul 14 12:04:29 volumiopi3 volumio[1067]: info: Applying required configuration parameters for plugin ampswitch
Jul 14 12:04:29 volumiopi3 volumio[1067]: info: PLUGIN START: ampswitch
Jul 14 12:04:29 volumiopi3 volumio[1067]: info: Error: Error: EBUSY: resource busy or locked, write
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] Setting UI defaults
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] Port: 24
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] Inverted: false
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] Latched: false
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] On pulse width: 500
Jul 14 12:04:31 volumiopi3 volumio[1067]: info: [ASDebug] Off pulse width: 500
Jul 14 12:04:38 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 14 12:04:38 volumiopi3 volumio[1067]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Jul 14 12:04:47 volumiopi3 volumio[1067]: info: Starting Uninstall of plugin system_controller - ampswitch
Jul 14 12:04:47 volumiopi3 volumio[1067]: info: Uninstalling plugin ampswitch
Jul 14 12:04:47 volumiopi3 volumio[1067]: info: [ASDebug] Port: 24
Jul 14 12:04:47 volumiopi3 volumio[1067]: info: [ASDebug] Inverted: false
Jul 14 12:04:47 volumiopi3 volumio[1067]: info: [ASDebug] Delay: 720
Jul 14 12:04:47 volumiopi3 volumio[1067]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 14 12:04:47 volumiopi3 volumio[1067]: TypeError: Cannot read property 'unexport' of undefined
Jul 14 12:04:47 volumiopi3 volumio[1067]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1239:45)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at Socket.emit (events.js:315:20)
Jul 14 12:04:47 volumiopi3 volumio[1067]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jul 14 12:04:47 volumiopi3 volumio[1067]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Jul 14 12:04:47 volumiopi3 volumio[1067]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 14 12:04:48 volumiopi3 sudo[5056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 12:03
Jul 14 12:04:48 volumiopi3 sudo[5056]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST"
VOLUMIO_VERSION="3.703"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"