-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-03-19 18:49:27 CET. -- Mar 19 18:48:02 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume65 Mar 19 18:48:02 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:02 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:02 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:02 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:11 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume60 Mar 19 18:48:11 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:11 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:11 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:11 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:16 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume50 Mar 19 18:48:16 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:16 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:16 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:16 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:35 volumiostreamer volumio[945]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Mar 19 18:48:35 volumiostreamer volumio[945]: info: FusionDsp - value distance L------- 0 R 0 Mar 19 18:48:35 volumiostreamer volumio[945]: info: FusionDsp - no delay needed Mar 19 18:48:35 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume+ Mar 19 18:48:35 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume- Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:35 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:35 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:35 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - crossfeed bauer Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - Loudness is ON true Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 19 18:48:36 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioGetState Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - volume level for loudness 50 gain applied 0.00 Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - crossfeed bauer Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - Loudness is ON true Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 19 18:48:36 volumiostreamer volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 18:48:36 volumiostreamer volumio[945]: info: CoreCommandRouter::Close All Modals sent Mar 19 18:48:36 volumiostreamer volumio[945]: info: FusionDsp - no preset used Mar 19 18:48:37 volumiostreamer volumio[945]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 19 18:48:38 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume60 Mar 19 18:48:38 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:38 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:38 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:38 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:39 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume55 Mar 19 18:48:39 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:39 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:39 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:39 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:40 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume50 Mar 19 18:48:40 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:40 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:40 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:40 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:41 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume55 Mar 19 18:48:41 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:41 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:41 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:41 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:44 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume50 Mar 19 18:48:44 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:44 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:44 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:44 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:48:53 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume65 Mar 19 18:48:53 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:48:53 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:48:53 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:48:53 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:01 volumiostreamer volumio[945]: info: CALLMETHOD: audio_interface fusiondsp reseteq Mar 19 18:49:01 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Mar 19 18:49:01 volumiostreamer volumio[945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 19 18:49:01 volumiostreamer volumio[945]: info: FusionDsp - crossfeed bauer Mar 19 18:49:01 volumiostreamer volumio[945]: info: FusionDsp - Loudness is ON true Mar 19 18:49:01 volumiostreamer volumio[945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 19 18:49:01 volumiostreamer volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 18:49:01 volumiostreamer volumio[945]: info: CoreCommandRouter::Close All Modals sent Mar 19 18:49:01 volumiostreamer volumio[945]: info: FusionDsp - no preset used Mar 19 18:49:02 volumiostreamer volumio[945]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 19 18:49:02 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume45 Mar 19 18:49:02 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:02 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:02 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:02 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:14 volumiostreamer volumio[945]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Mar 19 18:49:14 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Mar 19 18:49:14 volumiostreamer volumio[945]: info: FusionDsp - Value for usedpreset: Mar 19 18:49:14 volumiostreamer volumio[945]: error: FusionDsp - Value reading JSON file: {"geq15":"3,4,3,3,5,4.5,2.5,0,1.5,1.5,3.5,4,4,2.5,3","x2geq15":"3,4,3,3,5,4.5,2.5,0,1.5,1.5,3.5,4,4,2.5,3","state4preset":["None",false,false,50,0,0,0,"None",true,false,false,0,0,false]} Mar 19 18:49:14 volumiostreamer volumio[945]: info: FusionDsp - geq15 3,4,3,3,5,4.5,2.5,0,1.5,1.5,3.5,4,4,2.5,3 Mar 19 18:49:14 volumiostreamer volumio[945]: info: FusionDsp - value state4preset None,false,false,50,0,0,0,None,true,false,false,0,0,false Mar 19 18:49:15 volumiostreamer volumio[945]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 19 18:49:15 volumiostreamer volumio[945]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 19 18:49:15 volumiostreamer volumio[945]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 19 18:49:15 volumiostreamer volumio[945]: info: CoreCommandRouter::Close All Modals sent Mar 19 18:49:15 volumiostreamer volumio[945]: info: FusionDsp - Soundtrack-FusionDsp Mar 19 18:49:15 volumiostreamer volumio[945]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Mar 19 18:49:16 volumiostreamer volumio[945]: info: VolumeController::SetAlsaVolume60 Mar 19 18:49:16 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:16 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:16 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:16 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:21 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:21 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 1 Mar 19 18:49:21 volumiostreamer volumio[945]: info: Prefetching next song Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube" Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand addid "https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube" took 7 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: MPD COMMAND [object Object] Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: MPD COMMAND [object Object] Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 13ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ytmusic-play] Prefetched and added track to MPD queue: Izukari III Kafe Antzokia 30 01 2025 Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: options Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 14ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand consume 1 took 10 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 12ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 10ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 9ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: options Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: options Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:22 volumiostreamer volumio[945]: info: Mar 19 18:49:22 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: options Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand status took 15 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 11ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand status took 9 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 8ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand status took 7 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 6ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand status took 5 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198356,"duration":201,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"IZUKARI // Izu Hau (AZPIDATZIA)","artist":"Aitor Pikabea","album":null,"uri":"https://rr7---sn-8vq54voxn25po-cjol.googlevideo.com/videoplayback?expire=1742427963&ei=2wLbZ_rNDr2hp-oPtbixuQs&ip=77.209.48.47&id=o-AI5tG_DrT7PfWiaYjVc2_4QGc9WkMsLHQdQG4lJPyIh8&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406363%2C&mh=cy&mm=31%2C29&mn=sn-8vq54voxn25po-cjol%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=7&pl=19&rms=au%2Cau&initcwndbps=646250&bui=AccgBcMUn-qdBvr7Ar-Zkjx_Y1gpY-H9nmUcDR1Qyfm-KNhYLlWOJXDh3B7Q9IOsCnY9GhdmO7TZ62ut&spc=_S3wKjbJEiiQSlXjjV2F0qVAD974RvzHJcdmL4eROJBag-ZXWpqOe0uMFTF2G2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DFvr6HKNgcGvTdy69Njq_9QQ&rqh=1&gir=yes&clen=3283416&dur=201.061&lmt=1740690627113641&mt=1742405885&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=GaRmQ0NABVRzFQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP08PkpPWSPkGSgy1FTnkZaiaoXX2iqfNhGhwiGYYikhAiBanSQtzzqmaoQcyBe2ewuTJMaP8WMYEdMP6eFPeyC-CA%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAMF9h874Jmg8c4332IKPl9sf3mDuWKrcnIcai3ohiBGKAiEA9Qc9_WP92yCC5No7mR89qznLD1EmmcPGlNG9w2mYd_w%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:22 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198356,"duration":201,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"IZUKARI // Izu Hau (AZPIDATZIA)","artist":"Aitor Pikabea","album":null,"uri":"https://rr7---sn-8vq54voxn25po-cjol.googlevideo.com/videoplayback?expire=1742427963&ei=2wLbZ_rNDr2hp-oPtbixuQs&ip=77.209.48.47&id=o-AI5tG_DrT7PfWiaYjVc2_4QGc9WkMsLHQdQG4lJPyIh8&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406363%2C&mh=cy&mm=31%2C29&mn=sn-8vq54voxn25po-cjol%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=7&pl=19&rms=au%2Cau&initcwndbps=646250&bui=AccgBcMUn-qdBvr7Ar-Zkjx_Y1gpY-H9nmUcDR1Qyfm-KNhYLlWOJXDh3B7Q9IOsCnY9GhdmO7TZ62ut&spc=_S3wKjbJEiiQSlXjjV2F0qVAD974RvzHJcdmL4eROJBag-ZXWpqOe0uMFTF2G2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DFvr6HKNgcGvTdy69Njq_9QQ&rqh=1&gir=yes&clen=3283416&dur=201.061&lmt=1740690627113641&mt=1742405885&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=GaRmQ0NABVRzFQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP08PkpPWSPkGSgy1FTnkZaiaoXX2iqfNhGhwiGYYikhAiBanSQtzzqmaoQcyBe2ewuTJMaP8WMYEdMP6eFPeyC-CA%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAMF9h874Jmg8c4332IKPl9sf3mDuWKrcnIcai3ohiBGKAiEA9Qc9_WP92yCC5No7mR89qznLD1EmmcPGlNG9w2mYd_w%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:22 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198356,"duration":201,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"IZUKARI // Izu Hau (AZPIDATZIA)","artist":"Aitor Pikabea","album":null,"uri":"https://rr7---sn-8vq54voxn25po-cjol.googlevideo.com/videoplayback?expire=1742427963&ei=2wLbZ_rNDr2hp-oPtbixuQs&ip=77.209.48.47&id=o-AI5tG_DrT7PfWiaYjVc2_4QGc9WkMsLHQdQG4lJPyIh8&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406363%2C&mh=cy&mm=31%2C29&mn=sn-8vq54voxn25po-cjol%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=7&pl=19&rms=au%2Cau&initcwndbps=646250&bui=AccgBcMUn-qdBvr7Ar-Zkjx_Y1gpY-H9nmUcDR1Qyfm-KNhYLlWOJXDh3B7Q9IOsCnY9GhdmO7TZ62ut&spc=_S3wKjbJEiiQSlXjjV2F0qVAD974RvzHJcdmL4eROJBag-ZXWpqOe0uMFTF2G2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DFvr6HKNgcGvTdy69Njq_9QQ&rqh=1&gir=yes&clen=3283416&dur=201.061&lmt=1740690627113641&mt=1742405885&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=GaRmQ0NABVRzFQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP08PkpPWSPkGSgy1FTnkZaiaoXX2iqfNhGhwiGYYikhAiBanSQtzzqmaoQcyBe2ewuTJMaP8WMYEdMP6eFPeyC-CA%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAMF9h874Jmg8c4332IKPl9sf3mDuWKrcnIcai3ohiBGKAiEA9Qc9_WP92yCC5No7mR89qznLD1EmmcPGlNG9w2mYd_w%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:22 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198356,"duration":201,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"IZUKARI // Izu Hau (AZPIDATZIA)","artist":"Aitor Pikabea","album":null,"uri":"https://rr7---sn-8vq54voxn25po-cjol.googlevideo.com/videoplayback?expire=1742427963&ei=2wLbZ_rNDr2hp-oPtbixuQs&ip=77.209.48.47&id=o-AI5tG_DrT7PfWiaYjVc2_4QGc9WkMsLHQdQG4lJPyIh8&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406363%2C&mh=cy&mm=31%2C29&mn=sn-8vq54voxn25po-cjol%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=7&pl=19&rms=au%2Cau&initcwndbps=646250&bui=AccgBcMUn-qdBvr7Ar-Zkjx_Y1gpY-H9nmUcDR1Qyfm-KNhYLlWOJXDh3B7Q9IOsCnY9GhdmO7TZ62ut&spc=_S3wKjbJEiiQSlXjjV2F0qVAD974RvzHJcdmL4eROJBag-ZXWpqOe0uMFTF2G2g&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DFvr6HKNgcGvTdy69Njq_9QQ&rqh=1&gir=yes&clen=3283416&dur=201.061&lmt=1740690627113641&mt=1742405885&fvip=3&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=GaRmQ0NABVRzFQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRQIhAP08PkpPWSPkGSgy1FTnkZaiaoXX2iqfNhGhwiGYYikhAiBanSQtzzqmaoQcyBe2ewuTJMaP8WMYEdMP6eFPeyC-CA%3D%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAMF9h874Jmg8c4332IKPl9sf3mDuWKrcnIcai3ohiBGKAiEA9Qc9_WP92yCC5No7mR89qznLD1EmmcPGlNG9w2mYd_w%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:22 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:22 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 124ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 114ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 112ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: ------------------------------ 111ms Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:22 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:24 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:24 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:24 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:24 volumiostreamer volumio[945]: info: Mar 19 18:49:24 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:24 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:24 volumiostreamer volumio[945]: info: ------------------------------ 15ms Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand status took 13 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: ------------------------------ 12ms Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand status took 10 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: ------------------------------ 10ms Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand status took 8 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: ------------------------------ 8ms Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand status took 6 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 19 18:49:24 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseTrackInfo Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":289,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Izukari III Kafe Antzokia 30 01 2025","artist":"oskar azanza","album":null,"uri":"https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:25 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":289,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Izukari III Kafe Antzokia 30 01 2025","artist":"oskar azanza","album":null,"uri":"https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:25 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":289,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Izukari III Kafe Antzokia 30 01 2025","artist":"oskar azanza","album":null,"uri":"https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:25 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53,"duration":289,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Izukari III Kafe Antzokia 30 01 2025","artist":"oskar azanza","album":null,"uri":"https://rr4---sn-8vq54voxn25po-cjok.googlevideo.com/videoplayback?expire=1742428161&ei=oQPbZ8ebLua3mLAPgo_dgA4&ip=77.209.48.47&id=o-AKLndVVCR6z7b4baKW-2Avs7wUC4rJKYKHdSx7TRfqpd&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1742406561%2C&mh=KO&mm=31%2C29&mn=sn-8vq54voxn25po-cjok%2Csn-h5q7knel&ms=au%2Crdu&mv=m&mvi=4&pl=19&rms=au%2Cau&initcwndbps=741250&bui=AccgBcPOxvnxRuAdeO3a5KG4lIKRV1b4kahNzeU8jpgITMHKE3_rMnLsC_pT8i9dvu4oKoiuPhwTL3S7&spc=_S3wKk9Hk6ZVYikyXDIGHgmPgpbK2TihTdngVL0IIjqXhgECmCdXOnBoGmvmeiY&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=6Ii2c05PjxSh8d697HUO4msQ&rqh=1&gir=yes&clen=4405875&dur=289.301&lmt=1738280799971292&mt=1742406119&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=6208224&n=Y_UaDFRjIV3brA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRgIhAMlnpJT62N37LMlsmklwlN-AKoW5lAEjQdb8jYvcm50rAiEA6_VNXt1-OhkeO0kgN3yuYxGpsCiWZO8AvWwEq4tw3fM%3D&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRgIhAOcsBg9tieSjeSNFxfWeMhqPQ3-hjc6PlG9VLu5_YwtWAiEA_QFAkf3teB7eAyVxVoWhWAEusFSjK-ujJAlo42BTy9M%3D&pot=MpgBlGbDi7FwK86UWupSh1K6a6H1Dlniv4BZCO73dN7IQDbPcs84uO58bcDggnkK0TbznylBgcHa22A9zGfrkQfs7HymU_LuQzl-hjP5EtDPPdatvU8SRXkHNb4otDVPRmsXLc8FhLHMqzzVZN-dtX-ukfWFnKbUThC1ptjMUU_gu1Y7-6uM16tVade6qCttVWkVz13Q-b9mNao%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:25 volumiostreamer volumio[945]: info: Received an update from plugin. extracting info from payload Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 116ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 114ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 110ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 108ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioNext Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::next Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::next Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand next Mar 19 18:49:25 volumiostreamer volumio[945]: info: sendMpdCommand next took 13 milliseconds Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces system playlist update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Ignoring MPD Status Update Mar 19 18:49:25 volumiostreamer volumio[945]: info: Mar 19 18:49:25 volumiostreamer volumio[945]: ---------------------------- MPD announces state update: player Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::getState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::sendMpdCommand status Mar 19 18:49:25 volumiostreamer volumio[945]: error: updateQueue error: null Mar 19 18:49:25 volumiostreamer volumio[945]: error: updateQueue error: null Mar 19 18:49:25 volumiostreamer volumio[945]: error: updateQueue error: null Mar 19 18:49:25 volumiostreamer volumio[945]: error: updateQueue error: null Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 11ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: sendMpdCommand status took 9 milliseconds Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 9ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: sendMpdCommand status took 8 milliseconds Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 7ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: sendMpdCommand status took 7 milliseconds Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 6ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: sendMpdCommand status took 5 milliseconds Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: ControllerMpd::parseState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 0 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 0 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus play Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 1 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 1 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: No code Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 1 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 1 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: No code Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ControllerMpd::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::servicePushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CorePlayQueue::getTrack 1 Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 19 18:49:25 volumiostreamer volumio[945]: verbose: CURRENT POSITION 1 Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState stateService stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::syncState currentStatus stop Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: No code Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreStateMachine::pushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: CoreCommandRouter::volumioPushState Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 131ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 130ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 129ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: ------------------------------ 128ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: play PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] CurState: stop PrevState: play Mar 19 18:49:25 volumiostreamer volumio[945]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Mar 19 18:49:26 volumiostreamer volumio[945]: info: camilladsp spawned new process with pid 2478, instance 1, run: true Mar 19 18:49:26 volumiostreamer volumio[945]: info: [ASDebug] Togle GPIO: OFF Mar 19 18:49:26 volumiostreamer volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 18:49:26 volumiostreamer volumio[945]: Error: EBADF: bad file descriptor, write Mar 19 18:49:26 volumiostreamer volumio[945]: at Object.writeSync (fs.js:711:3) Mar 19 18:49:26 volumiostreamer volumio[945]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Mar 19 18:49:26 volumiostreamer volumio[945]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Mar 19 18:49:26 volumiostreamer volumio[945]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Mar 19 18:49:26 volumiostreamer volumio[945]: at listOnTimeout (internal/timers.js:554:17) Mar 19 18:49:26 volumiostreamer volumio[945]: at processTimers (internal/timers.js:497:7) { Mar 19 18:49:26 volumiostreamer volumio[945]: errno: -9, Mar 19 18:49:26 volumiostreamer volumio[945]: syscall: 'write', Mar 19 18:49:26 volumiostreamer volumio[945]: code: 'EBADF' Mar 19 18:49:26 volumiostreamer volumio[945]: } Mar 19 18:49:26 volumiostreamer volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 19 18:49:27 volumiostreamer sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-19 18:48 Mar 19 18:49:27 volumiostreamer sudo[2494]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"