-- Logs begin at Thu 2019-02-14 12:12:01 EET, end at Thu 2024-09-05 13:23:34 EEST. -- Sep 05 13:22:01 volumio volumio[812]: info: VolumeController::SetAlsaVolume81 Sep 05 13:22:01 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:01 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 81 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 100 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 81 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:02 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 81 Sep 05 13:22:02 volumio volumio[812]: info: VolumeController::SetAlsaVolume55 Sep 05 13:22:02 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:02 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 81 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 55 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:02 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 55 Sep 05 13:22:02 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:02 volumio volumio[812]: info: Listing playlists Sep 05 13:22:02 volumio volumio[812]: info: Listing playlists Sep 05 13:22:02 volumio volumio[812]: info: VolumeController::SetAlsaVolume99 Sep 05 13:22:02 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:02 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 55 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 99 Sep 05 13:22:02 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:02 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 99 Sep 05 13:22:04 volumio volumio[812]: info: VolumeController::SetAlsaVolume4 Sep 05 13:22:04 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:04 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:04 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:04 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Sep 05 13:22:04 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 99 Sep 05 13:22:04 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 4 Sep 05 13:22:04 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:04 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 4 Sep 05 13:22:04 volumio volumio[812]: info: VolumeController::SetAlsaVolume100 Sep 05 13:22:04 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:04 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:04 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:05 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 13:22:05 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 4 Sep 05 13:22:05 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 100 Sep 05 13:22:05 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:05 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 100 Sep 05 13:22:05 volumio volumio[812]: info: VolumeController::SetAlsaVolume0 Sep 05 13:22:05 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:05 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:05 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:06 volumio volumio[812]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Sep 05 13:22:06 volumio volumio[812]: info: Sending Spotify command with payload to local API: /player/volume Sep 05 13:22:06 volumio volumio[812]: info: VolumeController::SetAlsaVolume100 Sep 05 13:22:06 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:06 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:06 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:06 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 05 13:22:08 volumio volumio[812]: info: VolumeController::SetAlsaVolume3 Sep 05 13:22:08 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:08 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:08 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:08 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:08 volumio volumio[812]: SPOTIFY: SPOTIFY VOLUME 100 Sep 05 13:22:08 volumio volumio[812]: SPOTIFY: VOLUMIO VOLUME 3 Sep 05 13:22:08 volumio volumio[812]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 13:22:08 volumio volumio[812]: info: Setting Spotify Volume from Volumio: 3 Sep 05 13:22:10 volumio volumio[812]: SPOTIFY: SETTING SPOTIFY VOLUME 3 Sep 05 13:22:10 volumio volumio[812]: info: Sending Spotify command with payload to local API: /player/volume Sep 05 13:22:10 volumio go-librespot[2035]: time="2024-09-05T13:22:10+03:00" level=debug msg="update volume to 1966/65535" Sep 05 13:22:10 volumio go-librespot[2035]: time="2024-09-05T13:22:10+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 05 13:22:10 volumio go-librespot[2035]: time="2024-09-05T13:22:10+03:00" level=trace msg="emitting websocket event: volume" Sep 05 13:22:10 volumio volumio[812]: SPOTIFY: received: {"type":"volume","data":{"value":3,"max":100}} Sep 05 13:22:10 volumio volumio[812]: SPOTIFY: RECEIVED SPOTIFY VOLUME 3 Sep 05 13:22:10 volumio volumio[812]: SPOTIFY: received: {"type":"volume","data":{"value":3,"max":100}} Sep 05 13:22:10 volumio volumio[812]: SPOTIFY: RECEIVED SPOTIFY VOLUME 3 Sep 05 13:22:12 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:21 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:22:21 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:22 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:22:22 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:22 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:22 volumio volumio[812]: info: Listing playlists Sep 05 13:22:22 volumio volumio[812]: info: Listing playlists Sep 05 13:22:23 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:22:27 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:29 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:22:32 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:39 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:42 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:42 volumio volumio[812]: info: Listing playlists Sep 05 13:22:42 volumio volumio[812]: info: Listing playlists Sep 05 13:22:44 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::ClearQueue Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::stPlaybackTimer Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::serviceStop Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::serviceStop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::stop Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::addQueueItems Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::addQueueItems Sep 05 13:22:44 volumio volumio[812]: info: Preload queue cleared Sep 05 13:22:44 volumio volumio[812]: info: Adding Item to queue: upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@01-Піккардійська Терція-1994$Album$Music Sep 05 13:22:44 volumio volumio[812]: info: Exploding uri upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@01-Піккардійська Терція-1994$Album$Music in service upnp_browser Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: info: Sep 05 13:22:44 volumio volumio[812]: ---------------------------- MPD announces state update: player Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand stop took 150 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::getState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:22:44 volumio volumio[812]: info: Sep 05 13:22:44 volumio volumio[812]: ---------------------------- MPD announces state update: player Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::getState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:22:44 volumio volumio[812]: info: Sep 05 13:22:44 volumio volumio[812]: ---------------------------- MPD announces state update: player Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::getState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:22:44 volumio volumio[812]: info: Sep 05 13:22:44 volumio volumio[812]: ---------------------------- MPD announces state update: player Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::getState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand status Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand status took 21 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand status took 13 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand status took 11 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand status took 7 milliseconds Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseState Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 05 13:22:44 volumio volumio[812]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:22:44 volumio volumio[812]: verbose: ControllerMpd::parseTrackInfo Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::servicePushState Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Falling","artist":"Iyeoka","album":"Say Yes Evolved","uri":"https://rr4---sn-a5oxu-uoae.googlevideo.com/videoplayback?expire=1725553304&ei=OIbZZoGkNq-Ei9oP_OGoEA&ip=5.58.123.88&id=o-ANv2rIsX-JZZi3Wj6a4qvKHAlUC-soKrbgeDE8AyW8Vc&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=xx&mm=31%2C29&mn=sn-a5oxu-uoae%2Csn-3c27sn7e&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=24&ctier=A&pfa=5&gcr=ua&initcwndbps=1166250&hightc=yes&siu=1&bui=AQmm2extVINpjBpPnRz6vCV-fSszGog_L1KlIKt4QDgIwjtP8u5VY-KkVtjBqZQQvGJpj07LwA&spc=Mv1m9sYMCqJ_PjokQ-pYnR7EozWl64UN2NBgET56lsZZd3eE-Tth4OBCRidtPjI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K5B-8jDzqkLE1MUeyyiirfoQ&rqh=1&gir=yes&clen=7831499&dur=238.961&lmt=1714523059134305&mt=1725531184&fvip=12&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=1L029ENEaUdKgw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ABPmVW0wRgIhAPSBNsm68SvOX1gmgZi8EsivQCtXCLZ5RFBcCCm40NOmAiEAodABBcvm8NXVZuqOKgnrpPHWFSHEnjQoCHemme67I-0%3D&sig=AJfQdSswRQIgVQRaIffTt8Jm1RI8iccH--ow5oreHTCuhYXVuH8JG0YCIQC2xxte-3GuIkBxgfiUrHyk2ZdKzwygEnBRaSYlJ4oi9w%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Sep 05 13:22:44 volumio volumio[812]: verbose: CURRENT POSITION 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: No code Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::servicePushState Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Falling","artist":"Iyeoka","album":"Say Yes Evolved","uri":"https://rr4---sn-a5oxu-uoae.googlevideo.com/videoplayback?expire=1725553304&ei=OIbZZoGkNq-Ei9oP_OGoEA&ip=5.58.123.88&id=o-ANv2rIsX-JZZi3Wj6a4qvKHAlUC-soKrbgeDE8AyW8Vc&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=xx&mm=31%2C29&mn=sn-a5oxu-uoae%2Csn-3c27sn7e&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=24&ctier=A&pfa=5&gcr=ua&initcwndbps=1166250&hightc=yes&siu=1&bui=AQmm2extVINpjBpPnRz6vCV-fSszGog_L1KlIKt4QDgIwjtP8u5VY-KkVtjBqZQQvGJpj07LwA&spc=Mv1m9sYMCqJ_PjokQ-pYnR7EozWl64UN2NBgET56lsZZd3eE-Tth4OBCRidtPjI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K5B-8jDzqkLE1MUeyyiirfoQ&rqh=1&gir=yes&clen=7831499&dur=238.961&lmt=1714523059134305&mt=1725531184&fvip=12&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=1L029ENEaUdKgw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ABPmVW0wRgIhAPSBNsm68SvOX1gmgZi8EsivQCtXCLZ5RFBcCCm40NOmAiEAodABBcvm8NXVZuqOKgnrpPHWFSHEnjQoCHemme67I-0%3D&sig=AJfQdSswRQIgVQRaIffTt8Jm1RI8iccH--ow5oreHTCuhYXVuH8JG0YCIQC2xxte-3GuIkBxgfiUrHyk2ZdKzwygEnBRaSYlJ4oi9w%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Sep 05 13:22:44 volumio volumio[812]: verbose: CURRENT POSITION 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: No code Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::servicePushState Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Falling","artist":"Iyeoka","album":"Say Yes Evolved","uri":"https://rr4---sn-a5oxu-uoae.googlevideo.com/videoplayback?expire=1725553304&ei=OIbZZoGkNq-Ei9oP_OGoEA&ip=5.58.123.88&id=o-ANv2rIsX-JZZi3Wj6a4qvKHAlUC-soKrbgeDE8AyW8Vc&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=xx&mm=31%2C29&mn=sn-a5oxu-uoae%2Csn-3c27sn7e&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=24&ctier=A&pfa=5&gcr=ua&initcwndbps=1166250&hightc=yes&siu=1&bui=AQmm2extVINpjBpPnRz6vCV-fSszGog_L1KlIKt4QDgIwjtP8u5VY-KkVtjBqZQQvGJpj07LwA&spc=Mv1m9sYMCqJ_PjokQ-pYnR7EozWl64UN2NBgET56lsZZd3eE-Tth4OBCRidtPjI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K5B-8jDzqkLE1MUeyyiirfoQ&rqh=1&gir=yes&clen=7831499&dur=238.961&lmt=1714523059134305&mt=1725531184&fvip=12&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=1L029ENEaUdKgw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ABPmVW0wRgIhAPSBNsm68SvOX1gmgZi8EsivQCtXCLZ5RFBcCCm40NOmAiEAodABBcvm8NXVZuqOKgnrpPHWFSHEnjQoCHemme67I-0%3D&sig=AJfQdSswRQIgVQRaIffTt8Jm1RI8iccH--ow5oreHTCuhYXVuH8JG0YCIQC2xxte-3GuIkBxgfiUrHyk2ZdKzwygEnBRaSYlJ4oi9w%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Sep 05 13:22:44 volumio volumio[812]: verbose: CURRENT POSITION 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: No code Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: ControllerMpd::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::servicePushState Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Simply Falling","artist":"Iyeoka","album":"Say Yes Evolved","uri":"https://rr4---sn-a5oxu-uoae.googlevideo.com/videoplayback?expire=1725553304&ei=OIbZZoGkNq-Ei9oP_OGoEA&ip=5.58.123.88&id=o-ANv2rIsX-JZZi3Wj6a4qvKHAlUC-soKrbgeDE8AyW8Vc&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=xx&mm=31%2C29&mn=sn-a5oxu-uoae%2Csn-3c27sn7e&ms=au%2Crdu&mv=m&mvi=4&pcm2cms=yes&pl=24&ctier=A&pfa=5&gcr=ua&initcwndbps=1166250&hightc=yes&siu=1&bui=AQmm2extVINpjBpPnRz6vCV-fSszGog_L1KlIKt4QDgIwjtP8u5VY-KkVtjBqZQQvGJpj07LwA&spc=Mv1m9sYMCqJ_PjokQ-pYnR7EozWl64UN2NBgET56lsZZd3eE-Tth4OBCRidtPjI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=K5B-8jDzqkLE1MUeyyiirfoQ&rqh=1&gir=yes&clen=7831499&dur=238.961&lmt=1714523059134305&mt=1725531184&fvip=12&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=1L029ENEaUdKgw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Cinitcwndbps&lsig=ABPmVW0wRgIhAPSBNsm68SvOX1gmgZi8EsivQCtXCLZ5RFBcCCm40NOmAiEAodABBcvm8NXVZuqOKgnrpPHWFSHEnjQoCHemme67I-0%3D&sig=AJfQdSswRQIgVQRaIffTt8Jm1RI8iccH--ow5oreHTCuhYXVuH8JG0YCIQC2xxte-3GuIkBxgfiUrHyk2ZdKzwygEnBRaSYlJ4oi9w%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Sep 05 13:22:44 volumio volumio[812]: verbose: CURRENT POSITION 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState stateService stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::syncState currentStatus stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: No code Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::pushState Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushState Sep 05 13:22:44 volumio volumio[812]: info: ------------------------------ 387ms Sep 05 13:22:44 volumio volumio[812]: info: ------------------------------ 383ms Sep 05 13:22:44 volumio volumio[812]: info: ------------------------------ 381ms Sep 05 13:22:44 volumio volumio[812]: info: ------------------------------ 380ms Sep 05 13:22:44 volumio volumio[812]: info: touch_display: Setting screensaver timeout to 30 seconds. Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::play index 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::play index undefined Sep 05 13:22:44 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:44 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:44 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:22:45 volumio volumio[812]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 05 13:22:45 volumio volumio[812]: info: touch_display: Setting screensaver timeout to 30 seconds. Sep 05 13:22:45 volumio volumio[812]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 05 13:22:52 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:22:52 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:01 volumio volumio[812]: info: Preload queue cleared Sep 05 13:23:01 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 13:23:01 volumio volumio[812]: info: CoreStateMachine::ClearQueue Sep 05 13:23:01 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:23:01 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:01 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Sep 05 13:23:01 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:23:01 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:23:01 volumio volumio[812]: info: CoreStateMachine::addQueueItems Sep 05 13:23:01 volumio volumio[812]: info: CorePlayQueue::addQueueItems Sep 05 13:23:01 volumio volumio[812]: info: Preload queue cleared Sep 05 13:23:01 volumio volumio[812]: info: Adding Item to queue: upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@101 Classics - The Best Loved Classical Melodies: Mostly Baroque$Album$Music Sep 05 13:23:01 volumio volumio[812]: info: Exploding uri upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@101 Classics - The Best Loved Classical Melodies: Mostly Baroque$Album$Music in service upnp_browser Sep 05 13:23:02 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:23:02 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Sep 05 13:23:02 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Sep 05 13:23:02 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::play index 0 Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::play index undefined Sep 05 13:23:02 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:02 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:23:02 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:02 volumio volumio[812]: info: Listing playlists Sep 05 13:23:02 volumio volumio[812]: info: Listing playlists Sep 05 13:23:07 volumio volumio[812]: info: CoreCommandRouter::volumioSeek Sep 05 13:23:07 volumio volumio[812]: info: CoreStateMachine::seek Sep 05 13:23:07 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:10 volumio volumio[812]: info: Preload queue cleared Sep 05 13:23:10 volumio volumio[812]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::ClearQueue Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::clearPlayQueue Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:23:10 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::addQueueItems Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::addQueueItems Sep 05 13:23:10 volumio volumio[812]: info: Preload queue cleared Sep 05 13:23:10 volumio volumio[812]: info: Adding Item to queue: upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@15$Album$Music Sep 05 13:23:10 volumio volumio[812]: info: Exploding uri upnp/folder/http://192.168.0.161:2869/ContentDirectory/control@15$Album$Music in service upnp_browser Sep 05 13:23:10 volumio volumio[812]: info: CoreCommandRouter::volumioPushQueue Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::saveQueue Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::updateTrackBlock Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::getTrackBlock Sep 05 13:23:10 volumio volumio[812]: info: CoreCommandRouter::volumioPlay Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::play index 0 Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::stop Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::play index undefined Sep 05 13:23:10 volumio volumio[812]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 13:23:10 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:12 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:23:12 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:18 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:23:22 volumio volumio[812]: info: Preload queue cleared Sep 05 13:23:22 volumio volumio[812]: info: CoreCommandRouter::volumioGetState Sep 05 13:23:22 volumio volumio[812]: info: CorePlayQueue::getTrack 0 Sep 05 13:23:22 volumio volumio[812]: info: Listing playlists Sep 05 13:23:22 volumio volumio[812]: info: Listing playlists Sep 05 13:23:30 volumio volumio[812]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 05 13:23:31 volumio volumio[812]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 13:23:31 volumio volumio[812]: TypeError: Cannot read property 'length' of undefined Sep 05 13:23:31 volumio volumio[812]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 05 13:23:31 volumio volumio[812]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 05 13:23:31 volumio volumio[812]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 05 13:23:31 volumio volumio[812]: at Parser.emit (events.js:315:20) Sep 05 13:23:31 volumio volumio[812]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 05 13:23:31 volumio volumio[812]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 05 13:23:31 volumio volumio[812]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 05 13:23:31 volumio volumio[812]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 05 13:23:31 volumio volumio[812]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 05 13:23:31 volumio volumio[812]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 05 13:23:31 volumio volumio[812]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 05 13:23:31 volumio volumio[812]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 05 13:23:31 volumio volumio[812]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 05 13:23:31 volumio volumio[812]: at IncomingMessage.emit (events.js:327:22) Sep 05 13:23:31 volumio volumio[812]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 05 13:23:31 volumio volumio[812]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 05 13:23:31 volumio volumio[812]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 13:23:34 volumio sudo[2804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 13:22 Sep 05 13:23:34 volumio sudo[2804]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"