﻿22:19:02.905 - Start logging...
22:19:03.050 - ExceptionFilter::Init. restart=1
22:19:03.617 - DevicesManager::EnableCISupport
22:19:03.617 - CAVManager::SetSettings
22:19:03.633 - DevicesManager::DestroyAllTransponderManager
22:19:03.702 - SetDefaultNullCodecs
22:19:03.733 - IsWin8WithoutMpeg2Codecs 0
22:19:03.733 - IsWin8WithoutMpeg2Codecs 1
22:19:03.749 - IsWin8WithoutMpeg2Codecs 3
22:19:03.818 - SetDefaultNullCodecs
22:19:03.833 - IsWin8WithoutMpeg2Codecs 0
22:19:03.849 - IsWin8WithoutMpeg2Codecs 1
22:19:03.856 - IsWin8WithoutMpeg2Codecs 3
22:19:03.918 - DevicesManager::Start
22:19:03.918 - AVManager::Init
22:19:03.934 - RemoveAllCreateBlank needBlank1
22:19:03.971 - [101]OnChangeCurrentGraph2 -RemoveAllBlank
22:19:04.034 - VR: GUI
22:19:04.087 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
22:19:04.272 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
22:19:04.272 - AV:UpdateAudioParamsAfterActive
22:19:04.288 - AV:UpdateAudioProcessor spec=0 agc=1 bands=0
22:19:04.304 - AV:UpdateAudioProcessor spec=0 agc=1 bands=0
22:19:04.788 - DevicesManager::StartDevices...
22:19:04.804 - device netclient.0 22:19:04.820 - dev: Created  netclient.0 000001467473EBA0
OK
22:19:04.882 - Api2BaseModule.GetAdditionInfo
22:19:24.998 - 
22:19:25.013 - 
22:19:25.020 - ########################### Mark of start channel changing.###########################
22:19:25.036 - 
22:19:25.067 - GraphManager::MainChangeChannel 360  1 provs
22:19:25.082 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
22:19:25.082 - SetChannelLogic::SetChannel 0
22:19:25.098 - SetChannelLogic::SetNormalChannel
22:19:25.120 - DevicesManager::CreateCloneDevice
22:19:25.136 - DevicesManager::CreateCloneDevice OK - founded in list
22:19:25.151 - TransponderManager
22:19:25.167 - CTransponderManager::InitOnNewDevice 000001467478A6F8
22:19:25.183 - SetOwner N -> Incoming Stream Cache
22:19:25.198 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
22:19:25.214 - Normal TsInputTrafficProcessor.
22:19:25.220 - FilterManager::InitOnNewDevice 1
22:19:25.236 - TS router+MarkerFinder -> InputTrafficProcessor
22:19:25.252 - Incoming Stream Cache -> TS router+MarkerFinder
22:19:25.267 - InputTrafficProcessor -> Router to timeshift
22:19:25.283 - InputTrafficProcessor(FM) -> FilterManager
22:19:25.299 - ModuleList::Free
22:19:25.321 - ChannelGraph5.CChannelGraph5
22:19:25.336 - TransponderManager::AddChildChannel
22:19:25.352 - TimeShift.TimeShift
22:19:25.368 - TimeShift.Create 200 mbytes 
22:19:25.383 - ts:One channel
22:19:25.399 - HugeMemoryBlocksManager::GetBlock 200 mbytes OK
22:19:25.421 - TransponderManager::CreateTimeshiftBuffer OK
22:19:25.452 - TimeShift.CreateReadingCursor
22:19:25.452 - TransponderChannelSource.Plugins 00000146746C8080 csa 0000000000000000
22:19:25.468 - TsInputTrafficProcessor.AddChannel 0
22:19:25.499 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
22:19:25.521 - ChannelGraph5.SetActiveState 1
22:19:25.521 - TransponderManager::SelectChannel
22:19:25.553 - TsInputTrafficProcessor.SelectChannel 0
22:19:25.568 - Api2BaseModule.UpdateAllOpenedChannels 0
22:19:25.584 - AV:UpdateAudioParamsAfterActive
22:19:25.599 - AV:UpdateAudioProcessor spec=0 agc=1 bands=0
22:19:25.622 - GR:UpdateAudioProcessor old=0 new=1
22:19:25.637 - AP: created
22:19:25.653 - Create Audio Processor update 0000014676867880
22:19:25.653 - Graph::SetAudioChannelMask
22:19:25.653 - AudioProcessor::SetAudioChannelsMask
22:19:25.668 - AudioProcessor::Equalizer_SetBands
22:19:25.684 - AP: Spectrum_Enable 0
22:19:25.700 - AP: agc enable 1 1/4000
22:19:25.722 - 
22:19:25.737 - TransponderManager::SetTransponder (CH) 0 360
22:19:25.753 - *Transport TS
22:19:25.769 - Api2Device::SetChannel http://edge-tv-ll.facecast.io/evacoder_hls_hi/CkxfR1xNUAJwTgtXTBZTAJli/1.m3u8
22:19:25.869 - Api2Device::SetChannel result OK
22:19:25.869 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
22:19:25.885 - TsInputTrafficProcessor.OpenTransponder 1
22:19:25.900 - FilterManager::OnSetTransponder SetChannel
22:19:25.922 - TimeShift.Reset
22:19:25.938 - TimeShift.Cursor reset
22:19:25.954 - ReaderCursor::Reset.. OK
22:19:26.000 - Prereader.Reset ...-OK
22:19:26.023 - ModuleList::ChangeChannel 0
22:19:26.038 - [102]ChannelGraph5::StartChannel 360
22:19:26.054 - 
22:19:26.070 - [102]       #################### Start stream. (1.062ms) #######################
22:19:26.101 - 
22:19:25.900 - Api2BaseModule.OnTunerStateChanged Connecting
22:19:26.154 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=1
22:19:26.170 - TimeShift.Cursor SetChannel::v 0 (0)
22:19:26.185 - ReaderCursor::SetChannel.. OK
22:19:26.223 - ReaderCursor::SetPosition 0 (w=0)
22:19:26.239 - Prereader.SetChannel..
22:19:26.239 - Prereader.SetChannel unmask 1
22:19:26.239 - Plugin::SetChannel 43981
22:19:26.254 - TransponderManager::SelectChannel
22:19:26.254 - TsInputTrafficProcessor.SelectChannel 43981
22:19:26.254 - Api2BaseModule.UpdateAllOpenedChannels 1
22:19:26.254 - TsInputTrafficProcessor.UpdateChannel 43981
22:19:26.254 - GraphState: Undef by StartChannelIsReady
22:19:26.254 - PreScanner.StartChannelIsReady net=1 parseall=1
22:19:26.254 - VideoMediaTypeDetection: mpeg2
22:19:26.254 - SetMarkerAudioLngStream Gen New stream
22:19:26.254 - SimpleTsDemux::Init apid=-1, vpid=100
22:19:26.254 - GraphState: RequestPATScan by StartChannelIsReady TS
22:19:26.254 - Api2BaseModule.Filters_EnabledFullTS 1
22:19:26.270 - PlayTimePATParser.OpenParser 43981
22:19:26.270 - Open TSTS filter pid=0 data=0
22:19:26.270 - TSTS cur size=0
22:19:26.270 - Graph::SetVideoRect {8 8 712 568}
22:19:26.270 - [102]OnChangeCurrentGraph2 -endOfSetChannel
22:19:26.270 - ChannelGraph5.SetActiveState 1
22:19:26.286 - Api2BaseModule.OnTunerStateChanged Preparing
22:19:26.355 - Graph::SetVideoWindowRect {33 0 166 100}
22:19:26.355 - Graph::UpdateRenderMode
22:19:26.454 - GraphManager::MultiChannelRendering
22:19:26.454 - ChannelGraph5.SetActiveState 1
22:19:26.480 - Graph::SetVideoWindowRect {28 0 461 325}
22:19:26.487 - Api2BaseModule.OnTunerStateChanged Playing
22:19:26.736 - OK. 102
22:19:27.096 - Api2BaseModule.OnTunerStateChanged Playing
22:19:27.116 - Api2BaseModule.OnTunerStateChanged Connecting
22:19:27.124 - * s:0 q:0 tr=0 (0) Connecting
22:19:27.857 - * s:0 q:0 tr=0 (0) Connecting
22:19:28.656 - Api2BaseModule.OnTunerStateChanged Preparing
22:19:28.716 - Api2BaseModule.OnTunerStateChanged Playing
22:19:28.946 - * s:0 q:0 tr=0 (0) Preparing
22:19:29.194 - 
22:19:29.194 -        #################### First traffic. (4.187ms) #######################
22:19:29.212 - 
22:19:29.232 - PlayTimePATParser.EndTask
22:19:29.249 - TransportMarker: TS
22:19:29.249 - PreScanner.OnAfterScanPat count=2 {1;4096}
22:19:29.266 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
22:19:29.305 - Api2BaseModule.Filters_EnabledFullTS 0
22:19:29.322 - GraphState: RequestPMTScan by OnAfterScanPat
22:19:29.340 - PlayTimePmtParser::OpenParser SID=1 PMT=4096 unknow channel=1
22:19:29.360 - Open TSTS filter pid=4096 data=2
22:19:29.361 - TSTS cur size=212628
22:19:29.363 - TSTS quick finish
22:19:29.365 - --SwitchEngineTransport TS > TS
22:19:29.382 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
22:19:29.398 - PreScanner.OnTransportMarker TS
22:19:29.416 - --SwitchEngineTransport end
22:19:29.417 - PreScanner.OnAfterScanPmt pmt=4096
22:19:29.419 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
22:19:29.437 - PlayTimePmtParser::StopParser
22:19:29.593 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=0
22:19:29.593 - TimeShift.Cursor SetChannel::v 441988 (441988)
22:19:29.618 - Prereader.SetChannel unmask 1
22:19:29.635 - Plugin::SetChannel 1
22:19:29.651 - TransponderManager::SelectChannel
22:19:29.668 - TsInputTrafficProcessor.SelectChannel 1
22:19:29.684 - Api2BaseModule.UpdateAllOpenedChannels 1
22:19:29.709 - TsInputTrafficProcessor.UpdateChannel 1
22:19:29.725 - PreScanner.StartChannelIsReady net=1 parseall=0
22:19:29.742 - LngDefaultSelector.try select audio pid=257 type=1 lng=0-(goh,goh,rus) l=99
22:19:29.758 - ChannelGraph5::AfterDetectChannelParams
22:19:29.775 - VideoMediaTypeDetection: h.264
22:19:29.791 - SimpleTsDemux::Init apid=257, vpid=256
22:19:29.808 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
22:19:29.824 - demux: ProgDemux
22:19:29.848 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
22:19:29.858 - Prereader::ReaderCursor.. OK
22:19:29.891 - ReaderCursor::SetPosition 0 (w=749556)
22:19:29.909 - Prereader.StartPlayback Push buffering=0 recv=0000014676851F88 [StartChannelHelperBase(sl)]
22:19:29.928 - Prereader.StartPlayback after lock
22:19:29.946 - PreScanner.StartPmtParserIfNeed
22:19:29.963 - PlayTimePmtParser::OpenParser SID=1 PMT=4096 unknow channel=0
22:19:29.980 - Open TSTS filter pid=4096 data=2
22:19:29.996 - TSTS cur size=749556
22:19:29.947 - VideoParser::First packet 32401 h.264
22:19:30.031 - h264 detected 0 ms
22:19:30.033 - AudioParser::First packet 378
22:19:30.034 - AudioParser::packet 419
22:19:30.035 - AudioParser::AAC detected
22:19:30.037 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
22:19:30.038 - PreScanner.Post_InitRender [ReceiveTraffic]
22:19:30.085 - [102]sm: TvInitRenderer 1
22:19:30.085 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
22:19:30.103 - CalculBufferingSize prov=000001467478BB74 marker=1 request=1
22:19:30.120 - CalculBuffering 2000ms br=2000kb to 500 kbytes. By type
22:19:30.137 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
22:19:30.153 - Prereader::ReaderCursor.. OK
22:19:30.175 - ReaderCursor::SetPosition 0 (w=781704)
22:19:30.192 - Prereader.StartPlayback Pull buffering=512000 recv=0000000000000000 [EnablePullModeAndStartPlayback]
22:19:30.209 - Prereader.StartPlayback after lock
22:19:30.226 - Buffering/prereader size 500/3906 kbytes
22:19:30.242 - ------sGetVideoParams
22:19:30.260 - * s:100 q:100 tr=847692 (0) Preparing
22:19:30.260 - ChannelGraph5.CreateInterfacesAndRenderGraph
22:19:30.288 - subsm.StartChannel demux=ProgDemux tr=TS
22:19:30.300 - subsm.RecreateSubtitlesParsers 00000146766D0950 ts=1 subindex=-1
22:19:30.316 - subsm.Set Teletext parser 0
22:19:30.333 - subsm.RecreateSubtitlesParsers end
22:19:30.350 - subs: OnSetChannel
22:19:30.368 - CreateCustomGraph
22:19:30.384 - VideoType:H.264/AVC ffmpeg=0
22:19:30.405 - Video/4.H264 : MPEG2_VIDEO s={0,0,1280,720}, t={0,0,1280,720}, bmp=1280x720, r=16/9, br=0,frametime=40ms, IF=0x0, CF=0x0)
22:19:30.422 - Add video codec 212690fb-83e5-4526-8fd7-74478b7939cd
22:19:30.461 - OK
22:19:30.462 - CChannelGraph5::CreateRender 10
22:19:30.479 - VR: EVR Custom
22:19:30.496 - VR: create new render
22:19:30.627 - VR: Add video renderer Video Renderer - OK
22:19:30.628 - CreateAudioPartOfGraph
22:19:30.646 - AudioType:AAC/ADTS 
22:19:30.662 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 44100 16bit formatTag= 0x1600)
22:19:30.681 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
22:19:30.715 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Первичный звуковой драйвер OK (normal)
22:19:30.716 - Added audio renderer
22:19:30.733 - AP: created
22:19:30.750 - Added audio processor
22:19:30.776 - Connect codec to AP - OK
22:19:30.797 - Add and connect audio renderer - OK
22:19:30.802 - Graph::AfterRender
22:19:30.819 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
22:19:30.836 - AV:UpdateAudioParamsAfterActive
22:19:30.853 - AV:UpdateAudioProcessor spec=0 agc=1 bands=0
22:19:30.869 - GR:UpdateAudioProcessor old=1 new=1
22:19:30.887 - Graph::SetVolume 100% 0db
22:19:30.905 - Graph::SetAudioChannelMask
22:19:30.921 - AudioProcessor::SetAudioChannelsMask
22:19:30.937 - AudioProcessor::Equalizer_SetBands
22:19:30.955 - AP: agc enable 1 1/4000
22:19:30.977 - Graph::Run
22:19:31.054 - Graph::Run 1
22:19:31.055 - Graph::SetPictureParams
22:19:31.071 - Graph::SetVideoRect {16 8 1264 712}
22:19:31.087 - Graph::SetVideoWindowRect {0 25 490 300}
22:19:31.130 - 
22:19:31.142 - [0]########################### Mark of finish channel changing. (6.125ms) #############
22:19:31.152 - 
22:19:31.262 - 
22:19:31.266 - [0]########################### First video frame. (6.250ms) #############
22:19:31.272 - 
22:19:31.516 - CChannelGraph5::HideSubtitles
22:19:31.576 - * s:100 q:100 tr=1836760 (0) Playing
22:19:32.856 - * s:100 q:100 tr=1055620 (0) Playing
22:19:33.851 - * s:100 q:100 tr=344040 (0) Playing
22:19:34.859 - * s:100 q:100 tr=488424 (0) Playing
22:19:35.858 - * s:100 q:100 tr=376376 (0) Playing
22:19:36.855 - * s:100 q:100 tr=720980 (0) Playing
22:19:37.851 - * s:100 q:100 tr=380700 (0) Playing
22:19:38.854 - * s:100 q:100 tr=671724 (0) Playing
22:19:39.850 - * s:100 q:100 tr=369608 (0) Playing
22:19:40.854 - * s:100 q:100 tr=622468 (0) Playing
22:19:41.859 - * s:100 q:100 tr=342348 (0) Playing
22:19:42.855 - * s:100 q:100 tr=671912 (0) Playing
22:19:43.856 - * s:100 q:100 tr=13724 (0) Playing
22:19:44.858 - * s:100 q:100 tr=376376 (0) Playing
22:19:45.865 - * s:100 q:100 tr=504592 (0) Playing
22:19:46.871 - * s:100 q:100 tr=0 (0) Playing
22:19:47.868 - * s:100 q:100 tr=0 (0) Playing
22:19:48.869 - * s:100 q:100 tr=32336 (0) Playing
22:19:49.866 - * s:100 q:100 tr=589944 (0) Playing
22:19:50.868 - * s:100 q:100 tr=681876 (0) Playing
22:19:51.869 - * s:100 q:100 tr=458344 (0) Playing
22:19:52.879 - * s:100 q:100 tr=453832 (0) Playing
22:19:53.868 - * s:100 q:100 tr=97948 (0) Playing
22:19:54.877 - * s:100 q:100 tr=639012 (0) Playing
22:19:55.868 - * s:100 q:100 tr=215824 (0) Playing
22:19:56.871 - * s:100 q:100 tr=392732 (0) Playing
22:19:57.869 - * s:100 q:100 tr=524708 (0) Playing
22:19:58.866 - * s:100 q:100 tr=0 (0) Playing
22:19:59.867 - * s:100 q:100 tr=0 (0) Playing
22:20:00.867 - * s:100 q:100 tr=32336 (0) Playing
22:20:01.868 - * s:100 q:100 tr=639012 (0) Playing
22:20:02.872 - * s:100 q:100 tr=247784 (0) Playing
22:20:03.868 - * s:100 q:100 tr=425632 (0) Playing
22:20:04.870 - * s:100 q:100 tr=802760 (0) Playing
22:20:05.128 - ChannelGraph5.~CChannelGraph5
22:20:05.128 - Graph::Stop
22:20:05.187 - Before Stop ...22:20:05.212 - OnGraphStop ...22:20:05.220 - PreScanner.Stop
22:20:05.233 - GraphState: Undef by PreScanner.Stop
22:20:05.250 - PlayTimePmtParser::StopParser
22:20:05.277 - subsm.StopChannel
22:20:05.296 - VR: Disconnect
22:20:05.313 - Graph::ReleaseAllFilters
22:20:05.335 - release: Prog Audio renderer - RR - OK.
22:20:05.369 - release: Video Renderer - RR - OK.
22:20:05.416 - release: Microsoft DTV-DVD Video Decoder - RR - OK.
22:20:05.452 - release: Audio Processor - RR - OK.
22:20:05.493 - release: Microsoft DTV-DVD Audio Decoder - RR - OK.
22:20:05.529 - FilterGraph: 1
22:20:05.552 - ChannelGraph5.~CChannelGraph5 finish
22:20:05.587 - TransponderManager::CloseChannel
22:20:05.592 - TsInputTrafficProcessor.RemoveChannel 1
22:20:05.612 - ~TransponderChannelSource
22:20:05.757 - TimeShift.DestroyReadingCursor
22:20:05.757 - Api2BaseModule.UpdateAllOpenedChannels 0
22:20:05.772 - Destroy TSTS filter pid=0
22:20:05.795 - PlayTimePmtParser::StopParser
22:20:05.795 - Destroy TSTS filter pid=4096
22:20:05.795 - DevicesManager::DestroyTransponderManager
22:20:05.811 - Api2Device::SetChannel - None
22:20:05.896 - Api2Device::SetChannel result OK
22:20:05.896 - SetOwner N -> 0
22:20:05.911 - SetOwner SetTransponder null
22:20:05.927 - ~TransponderManager
22:20:05.943 - SetOwner N -> 0
22:20:05.958 - SetOwner SetTransponder null
22:20:05.980 - TimeShift.~TimeShift
22:20:05.996 - HugeMemoryBlocksManager::Free
22:20:06.012 - ModuleList::Free
22:20:06.043 - [103]OnChangeCurrentGraph2 -CloseGraph
22:20:06.112 - VR: GUI
22:20:06.174 - GraphManager::MultiChannelRendering
22:20:06.312 - OSDManager::Window_Add
22:20:06.328 - OSDManager::Window_Show
22:20:06.397 - * s:100 q:100 tr=15934069 (0) Playing
22:20:06.482 - OSDManager::Window_Show
22:20:06.497 - OSDManager::Window_Show
22:20:07.215 - * s:0 q:0 tr=0 (0) Playing
22:20:08.219 - * s:0 q:0 tr=0 (0) Playing
22:20:08.504 - OSDManager::Window_Show
22:20:08.504 - OSDManager::Window_Delete
22:20:08.551 - 
22:20:08.566 - Stop engine
22:20:08.566 - DevicesManager::Stop
22:20:08.566 - DevicesManager::Free devices
22:20:08.566 - SDP:Destroy netclient.0 own=0000000000000000
22:20:08.589 - SDP:after destroy device
22:20:08.604 - DevicesManager::Free RC
22:20:08.620 - Engine stopped
22:20:08.682 - 
22:20:08.682 - Stop engine
22:20:08.689 - DevicesManager::Stop
22:20:08.704 - DevicesManager::Free devices
22:20:08.736 - Engine stopped
22:20:08.889 - HugeMemoryBlocksManager::FreeAll
