#10
by kostan
Подвисает в первую очередь лист - список каналов - UI, вроде бы так это называется.
Вот отрезок лога, когда подвисает список каналов. Это не зависание, а подвисание - включённый канал в списке каналов, по коему я кликнул, не подсвечивается долго.
01:46:41.936 - ########################### Mark of start channel changing.###########################
01:46:41.937 -
01:46:41.938 - GraphManager::MainChangeChannel NBC 1 provs
01:46:41.939 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
01:46:41.940 - SetChannelLogic::SetChannel 0
01:46:41.941 - SetChannelLogic::SetNormalChannel
01:46:41.943 - [102]Stop channel
01:46:41.943 - PreScanner.Stop
01:46:41.944 - GraphState: Undef by PreScanner.Stop
01:46:41.946 - PlayTimePmtParser::StopParser
01:46:41.947 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
01:46:41.948 - Prereader::ReaderCursor.. OK
01:46:41.949 - ReaderCursor::SetPosition 0 (w=14561622760620032)
01:46:41.950 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
01:46:41.951 - Prereader.StartPlayback after lock
01:46:41.951 - subsm.StopChannel
01:46:41.953 - SS_CC: SetChannel ts2pes
01:46:41.953 - DestroyPlayer th=0
01:46:41.955 - Graph::Stop
01:46:41.989 - Before Stop ...01:46:41.990 - OnGraphStop ...01:46:42.011 - VR: Disconnect
01:46:42.013 - Graph::ReleaseAllFilters
01:46:42.014 - release: Prog Audio renderer - RR - OK.
01:46:42.020 - release: Video Renderer - RR - OK.
01:46:42.077 - release: Video decoder - RR - OK.
01:46:42.079 - release: Audio Processor - RR - OK.
01:46:42.080 - release: Microsoft DTV-DVD Audio Decoder - RR - OK.
01:46:42.081 - FilterGraph: 1
01:46:42.085 - Delete timer 1000 DirectShowPlayer
01:46:42.085 - Graph::ReleaseAllFilters
01:46:42.096 - DestroyPlayer - after
01:46:42.096 - **** StopChannel time 0.156ms
01:46:42.097 -
01:46:42.098 - TransponderManager::SetTransponder (CH) 0 NBC
01:46:42.099 - *Transport TS
01:46:42.100 - Api2Device::SetChannel **********
01:46:42.108 - skip old generation traffic!
01:46:42.108 - skip old generation traffic!
01:46:42.111 - skip old generation traffic!
01:46:42.111 - skip old generation traffic!
01:46:42.112 - skip old generation traffic!
01:46:42.113 - skip old generation traffic!
01:46:42.116 - Delete timer 1000 HlsReader
01:46:42.119 - Api2BaseModule.OnTunerStateChanged Connecting
01:46:42.121 - skip old generation traffic!
01:46:42.121 - skip old generation traffic!
01:46:42.154 - Api2Device::SetChannel result OK
01:46:42.154 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
01:46:42.155 - TsInputTrafficProcessor.OpenTransponder 1
01:46:42.155 - FilterManager::OnSetTransponder SetChannel
01:46:42.156 - TimeShift.Reset
01:46:42.156 - TimeShift.Cursor reset
01:46:42.156 - ReaderCursor::Reset.. OK
01:46:42.157 - Prereader.Reset ...-OK
01:46:42.158 - Create EPGparser on SetChannel
01:46:42.159 - DvbEpgParser::SetChannel ID=43b80002.db87638580df7570.1
01:46:42.161 - DvbEpgParser::mode normal pos=0
01:46:42.161 - ModuleList::ChangeChannel 0
01:46:42.162 - **** SetTransponder time 0.078ms
01:46:42.162 - [102]ChannelGraph5::StartChannel NBC
01:46:42.163 - AV:UpdateAudioParamsAfterActive
01:46:42.163 - AV:UpdateAudioProcessor spec=0 agc=1 bands=-200
01:46:42.164 - GR:UpdateAudioProcessor old=0 new=1
01:46:42.164 - CPCG SetVolume 00000000 100
01:46:42.165 -
01:46:42.165 - [102] #################### Start stream. (0.234ms) #######################
01:46:42.165 -
01:46:42.195 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
01:46:42.195 - TimeShift.Cursor SetChannel::v 0 (0)
01:46:42.195 - ReaderCursor::SetChannel.. OK
01:46:42.196 - ReaderCursor::SetPosition 0 (w=0)
01:46:42.197 - Prereader.SetChannel..
01:46:42.197 - Prereader.SetChannel unmask 1
01:46:42.198 - Plugin::SetChannel 1
01:46:42.198 - TransponderManager::SelectChannel
01:46:42.198 - TsInputTrafficProcessor.SelectChannel 1
01:46:42.199 - Api2BaseModule.UpdateAllOpenedChannels 1
01:46:42.199 - TsInputTrafficProcessor.UpdateChannel 1
01:46:42.200 - GraphState: Undef by StartChannelIsReady
01:46:42.200 - PreScanner.StartChannelIsReady net=1 parseall=1
01:46:42.200 - VideoMediaTypeDetection: h.264
01:46:42.201 - SetMarkerAudioLngStream Gen New stream
01:46:42.201 - SimpleTsDemux::Init apid=221, vpid=211
01:46:42.202 - GraphState: RequestPATScan by StartChannelIsReady TS
01:46:42.202 - Api2BaseModule.Filters_EnabledFullTS 1
01:46:42.203 - PlayTimePATParser.OpenParser 1
01:46:42.203 - Open TSTS filter pid=0 data=0
01:46:42.204 - TSTS cur size=0
01:46:42.204 - **** StartChanbnel time 0.031ms
01:46:42.205 - [102]OnChangeCurrentGraph2 -endOfSetChannel
01:46:42.205 - ChannelGraph.SetActiveState 1
01:46:42.215 - [102]**** SetChannel is finished (OK). Duration 0.281ms
01:46:42.284 - Api2BaseModule.OnTunerStateChanged Preparing
01:46:42.315 -
01:46:42.315 -
01:46:42.316 - ########################### Mark of start channel changing.###########################
01:46:42.316 -
01:46:42.316 - GraphManager::MainChangeChannel CBC St. John's 1 provs
01:46:42.317 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
01:46:42.317 - SetChannelLogic::SetChannel 0
01:46:42.318 - SetChannelLogic::SetNormalChannel
01:46:42.319 - [102]Stop channel
01:46:42.319 - PreScanner.Stop
01:46:42.320 - GraphState: Undef by PreScanner.Stop
01:46:42.320 - PlayTimePmtParser::StopParser
01:46:42.321 - Api2BaseModule.Filters_EnabledFullTS 0
01:46:42.321 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
01:46:42.321 - Prereader::ReaderCursor.. OK
01:46:42.323 - ReaderCursor::SetPosition 0 (w=0)
01:46:42.323 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
01:46:42.324 - Prereader.StartPlayback after lock
01:46:42.324 - subsm.StopChannel
01:46:42.324 - SS_CC: SetChannel ts2pes
01:46:42.325 - **** StopChannel time 0.015ms
01:46:42.325 -
01:46:42.326 - TransponderManager::SetTransponder (CH) 0 CBC St. John's
01:46:42.326 - *Transport TS
01:46:42.326 - Api2Device::SetChannel ********
01:46:42.341 - Api2Device::SetChannel result OK
01:46:42.341 - Api2BaseModule.OnTunerStateChanged Connecting
01:46:42.341 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
01:46:42.341 - TsInputTrafficProcessor.OpenTransponder 1
01:46:42.342 - FilterManager::OnSetTransponder SetChannel
01:46:42.342 - TimeShift.Reset
01:46:42.343 - TimeShift.Cursor reset
01:46:42.343 - ReaderCursor::Reset.. OK
01:46:42.345 - Prereader.Reset ...-OK
01:46:42.350 - ModuleList::ChangeChannel 0
01:46:42.350 - **** SetTransponder time 0.031ms
01:46:42.351 - [102]ChannelGraph5::StartChannel CBC St. John's
01:46:42.351 - AV:UpdateAudioParamsAfterActive
01:46:42.352 - AV:UpdateAudioProcessor spec=0 agc=1 bands=-200
01:46:42.352 - GR:UpdateAudioProcessor old=0 new=1
01:46:42.353 - CPCG SetVolume 00000000 100
01:46:42.353 -
01:46:42.354 - [102] #################### Start stream. (0.046ms) #######################
01:46:42.354 -
01:46:42.383 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
01:46:42.383 - TimeShift.Cursor SetChannel::v 0 (0)
01:46:42.384 - ReaderCursor::SetChannel.. OK
01:46:42.385 - ReaderCursor::SetPosition 0 (w=0)
01:46:42.385 - Prereader.SetChannel..
01:46:42.386 - Prereader.SetChannel unmask 1
01:46:42.386 - Plugin::SetChannel 1
01:46:42.386 - TransponderManager::SelectChannel
01:46:42.387 - TsInputTrafficProcessor.SelectChannel 1
01:46:42.387 - Api2BaseModule.UpdateAllOpenedChannels 1
01:46:42.387 - TsInputTrafficProcessor.UpdateChannel 1
01:46:42.388 - GraphState: Undef by StartChannelIsReady
01:46:42.388 - PreScanner.StartChannelIsReady net=1 parseall=1
01:46:42.389 - VideoMediaTypeDetection: h.264
01:46:42.389 - SetMarkerAudioLngStream Gen New stream
01:46:42.389 - SimpleTsDemux::Init apid=221, vpid=211
01:46:42.390 - GraphState: RequestPATScan by StartChannelIsReady TS
01:46:42.390 - Api2BaseModule.Filters_EnabledFullTS 1
01:46:42.390 - PlayTimePATParser.OpenParser 1
01:46:42.391 - Open TSTS filter pid=0 data=0
01:46:42.391 - TSTS cur size=0
01:46:42.392 - **** StartChanbnel time 0.032ms
01:46:42.392 - [102]OnChangeCurrentGraph2 -endOfSetChannel
01:46:42.393 - ChannelGraph.SetActiveState 1
01:46:42.399 - Api2BaseModule.OnTunerStateChanged Preparing
01:46:42.405 - [102]**** SetChannel is finished (OK). Duration 0.093ms
01:46:42.430 - Api2BaseModule.OnTunerStateChanged Playing
01:46:42.476 - Api2BaseModule.OnTunerStateChanged Playing
01:46:42.506 - Add timer 1000 HlsReader
01:46:42.522 - TransportMarker: TS
01:46:42.522 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
01:46:42.523 -
01:46:42.524 - #################### First traffic. (0.218ms) #######################
01:46:42.524 -
01:46:42.525 - PlayTimePATParser.EndTask
01:46:42.536 - --SwitchEngineTransport TS > TS
01:46:42.536 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
01:46:42.537 - PreScanner.OnTransportMarker TS
01:46:42.538 - --SwitchEngineTransport end
01:46:42.538 - PreScanner.OnAfterScanPat count=2 {1;4095}
01:46:42.539 - Api2BaseModule.Filters_EnabledFullTS 0
01:46:42.540 - GraphState: RequestPMTScan by OnAfterScanPat
01:46:42.540 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
01:46:42.541 - Open TSTS filter pid=4095 data=2
01:46:42.541 - TSTS cur size=148332
01:46:42.542 - TSTS quick finish
01:46:42.545 - PreScanner.OnAfterScanPmt pmt=4095
01:46:42.545 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
01:46:42.546 - PlayTimePmtParser::StopParser
01:46:42.582 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
01:46:42.582 - TimeShift.Cursor SetChannel::v 602540 (602540)
01:46:42.584 - Prereader.SetChannel unmask 1
01:46:42.584 - Plugin::SetChannel 1
01:46:42.585 - TransponderManager::SelectChannel
01:46:42.586 - TsInputTrafficProcessor.SelectChannel 1
01:46:42.587 - Api2BaseModule.UpdateAllOpenedChannels 1
01:46:42.588 - TsInputTrafficProcessor.UpdateChannel 1
01:46:42.589 - PreScanner.StartChannelIsReady net=1 parseall=0
01:46:42.589 - LngDefaultSelector.try select audio pid=221 type=1 lng=0-(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
01:46:42.590 - ChannelGraph::AfterDetectChannelParams
01:46:42.591 - VideoMediaTypeDetection: h.264
01:46:42.591 - SimpleTsDemux::Init apid=221, vpid=211
01:46:42.592 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
01:46:42.592 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
01:46:42.593 - Prereader::ReaderCursor.. OK
01:46:42.595 - ReaderCursor::SetPosition 0 (w=3979132580921344)
01:46:42.596 - Prereader.StartPlayback Push buffering=0 recv=0EC8725C [StartChannelHelperBase(sl)]
01:46:42.596 - Prereader.StartPlayback after lock
01:46:42.597 - PreScanner.StartPmtParserIfNeed
01:46:42.597 - VideoParser::First packet 81660 h.264
01:46:42.598 - h264 detected 0 ms
01:46:42.597 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
01:46:42.599 - AudioParser::First packet 526
01:46:42.600 - AudioParser::aac detected
01:46:42.600 - Open TSTS filter pid=4095 data=2
01:46:42.602 - TSTS cur size=999596
01:46:42.602 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
01:46:42.604 - PreScanner.Post_InitRender [ReceiveTraffic]
01:46:42.605 - subsm.StartChannel demux=0 tr=TS
01:46:42.606 - subsm.RecreateSubtitlesParsers 00000000 ts=1 subindex=1 st=3
01:46:42.608 -
01:46:42.609 - [102] #################### Init Render. (0.296ms) #######################
01:46:42.609 -
01:46:42.611 - ------sGetVideoParams
01:46:42.611 - video:h264 SD
01:46:42.612 - selector PlaybackTag=none videocodec=DS/ ts=1
01:46:42.613 - Channel PlaybackWay after check DS
01:46:42.615 - GraphState: GraphStarted by AsyncInitRender
01:46:42.615 - CalculBufferingSize prov=0D4E7EA4 marker=1 request=1
01:46:42.616 - CalculBuffering 2000ms br=2000kb to 500 kbytes. By type
01:46:42.616 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
01:46:42.617 - Prereader::ReaderCursor.. OK
01:46:42.618 - ReaderCursor::SetPosition 0 (w=5026400226508800)
01:46:42.619 - Prereader.StartPlayback Pull buffering=512000 recv=00000000 [EnablePullModeAndStartPlayback]
01:46:42.620 - Prereader.StartPlayback after lock
01:46:42.621 - Buffering/prereader size 500/3906 kbytes
01:46:42.621 - Not same player! old 0 new 13 prev 13
01:46:42.622 - ------sGetVideoParams
01:46:42.627 - Add timer 1000 DirectShowPlayer
01:46:42.629 - Graph::SetVideoWindowRect {294 0 1228 934}
01:46:42.630 - Graph::UpdateRenderMode
01:46:42.631 - Graph::SetVideoRect {8 8 712 568}
01:46:42.631 - Graph::SetVideoWindowRect {139 0 1384 934}
01:46:42.632 - demux: ProgDemux
01:46:42.639 - ------sGetVideoParams
01:46:42.639 - ChannelGraph5.CreateInterfacesAndRenderGraph
01:46:42.645 - CreateCustomGraph
01:46:42.646 - VideoType:H.264/AVC ffmpeg=0
01:46:42.647 - Video/4.H264 : MPEG2_VIDEO s={0,0,960,540}, t={0,0,960,540}, bmp=960x544, r=30/17, br=0,frametime=33ms, IF=0x0, CF=0x0)
01:46:42.648 - Add video codec ee30215d-164f-4a92-a4eb-9d4c13390f9f
01:46:42.652 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 08253E5C
01:46:42.654 - OK
01:46:42.655 - CChannelGraph5::CreateRender 6
01:46:42.656 - VR: EVR
01:46:42.657 - VR: create new render
01:46:42.867 - VR: Add video renderer Video Renderer - OK
01:46:42.868 - CreateAudioPartOfGraph
01:46:42.868 - AudioType:aac/adts
01:46:42.869 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 48000 16bit formatTag= 0x1600)
01:46:42.871 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
01:46:42.890 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:FxSound Speakers (FxSound Audio Enhancer) OK (normal)
01:46:42.890 - Added audio renderer
01:46:42.891 - AP: created
01:46:42.892 - Added audio processor
01:46:42.897 - Connect codec to AP - OK
01:46:42.905 - Add and connect audio renderer - OK
01:46:42.905 - Graph::AfterRender
01:46:42.913 - AV:UpdateAudioParamsAfterActive
01:46:42.913 - AV:UpdateAudioProcessor spec=0 agc=1 bands=-200
01:46:42.915 - GR:UpdateAudioProcessor old=1 new=1
01:46:42.916 - CPCG SetVolume 1338E318 100
01:46:42.917 - Graph::SetVolume 100% 0db
01:46:42.918 - AudioProcessor::Equalizer_SetBands
01:46:42.919 - AP: agc enable 1 1/4000
01:46:42.920 - Graph::Run
01:46:42.932 - Graph::Run 1
01:46:42.932 - Graph::SetVideoRect {12 8 948 536}
01:46:42.940 - Graph::SetVideoWindowRect {0 35 1523 898}
01:46:42.950 - AV:UpdateAudioParamsAfterActive
01:46:42.950 - AV:UpdateAudioProcessor spec=0 agc=1 bands=-200
01:46:42.951 - GR:UpdateAudioProcessor old=1 new=1
01:46:42.952 - CPCG SetVolume 1338E318 100
01:46:42.953 - Graph::SetVolume 100% 0db
01:46:42.954 - AudioProcessor::Equalizer_SetBands
01:46:42.955 - Graph::Run
01:46:42.956 - Graph::Run 0
01:46:42.956 - subsm.StartChannel demux=ProgDemux tr=TS
01:46:42.958 - subsm.RecreateSubtitlesParsers 08314E30 ts=1 subindex=1 st=3
01:46:42.959 - subsm.Stop teletext parser
01:46:42.959 - SS_CC: SetChannel ts2pes
01:46:42.960 - SS_CC: PES2TS pid
01:46:42.961 - subsm.RecreateSubtitlesParsers end
01:46:42.962 - subs: OnSetChannel
01:46:42.966 - ------sGetVideoParams
01:46:42.995 -
01:46:42.995 - [0]########################### Mark of finish channel changing. (0.687ms) #############
01:46:42.996 -
01:46:43.110 - Graph::OnVideoWindowPositionChanged
01:46:43.282 - * s:100 q:100 tr=5385448 (0) - ntt31 dmx6298 -1 Playing
01:46:44.072 - * s:100 q:100 tr=5420980 (0) - ntt109 dmx6871 -1 Playing
01:46:44.688 - * s:100 q:100 tr=1165788 (0) - ntt63 dmx6230 -1 Playing
01:46:45.686 - * s:100 q:100 tr=3009504 (0) - ntt47 dmx6765 -1 Playing
Вот в течение всего этого времени кликнутый канал не подсвечивался. Секунды полторы примерно. Мне кажется, что лог даже не отражает полностью того времени, что кликнутый канал не подсвечивался.
А обычное время подсветки канала - 0.3-0.4 секунды.
Вот этот отрезок лога показывает то, о чём я говорю. Я переключаю каналы с одинаковою скоростью, но посмотрите на разницу во времени между переключениями в начале лога и в конце:
46:10.673 SendKey:D2
46:10.673 Execute action (key):ChMinus
46:10.776 VP: Update #102 count=1
46:10.777 UpdatePositions
46:10.940 SendKey:D2
46:10.941 Execute action (key):ChMinus
46:11.195 VP: Update #102 count=1
46:11.196 UpdatePositions
46:11.308 SendKey:D2
46:11.309 Execute action (key):ChMinus
46:11.402 VP: Update #102 count=1
46:11.403 UpdatePositions
46:11.416 OSD:RadioChannelInfo2 OnSetChannel ch
46:11.570 SendKey:D2
46:11.571 Execute action (key):ChMinus
46:11.682 VP: Update #102 count=1
46:11.682 UpdatePositions
46:11.695 OSD:TvChannelInfo_Only OnSetChannel ch
46:12.095 SendKey:D2
46:12.096 Execute action (key):ChMinus
46:12.203 VP: Update #102 count=1
46:12.204 UpdatePositions
46:12.294 SendKey:D2
46:12.295 Execute action (key):ChMinus
46:12.404 VP: Update #102 count=1
46:12.405 UpdatePositions
46:12.637 OnAfterRenderInit
46:12.956 OnAfterRenderInit
46:13.062 SendKey:D2
46:13.062 Execute action (key):ChMinus
46:13.273 VP: Update #102 count=1
46:13.273 UpdatePositions
46:14.503 OnAfterRenderInit
46:14.820 OnAfterRenderInit
46:16.293 SendKey:D2
46:16.293 Execute action (key):ChMinus
46:17.559 VP: Update #102 count=1
46:17.560 UpdatePositions
46:18.019 OnAfterRenderInit
46:19.877 OnAfterRenderInit
46:25.675 OSD: Done
В начале лога разница во времени - 0.3-0.4 секунды, но предпредпоследнее переключение - 0.8 секунды, предпоследнее - 3(!) секунды, а последнее - 4(?) секунды. Это я и имею в виду, когда говорю о подтормаживании.
Обычно каналы переключаемы и список каналов (UI) на это реагирует вовремя - за 0.3-0.4 секунды, но иногда время отклика увеличивается до аж, как тут мы видим, трёх-четырёх секунд.
Я послал полный лог вам на почту, чтобы вы могли посмотреть эти места подробно.
Может, от кодеков зависит? Мне кажется, Майкрософтовские быстрее ЛАВа.