﻿19:57:05.066 - Start logging...
19:57:05.066 - 
19:57:05.066 - Start engine 6.210
19:57:05.066 - ExceptionFilter::Init. restart=1
19:57:10.775 - DevicesManager::Start
19:57:10.775 - CAVManager::Init
19:57:10.824 - AV settings loaded
19:57:10.871 - VR: GUI
19:57:10.886 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:10.996 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:10.996 - AV:UpdateAudioParamsAfterActive
19:57:10.996 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:10.996 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:12.025 - DevicesManager::StartDevices...
19:57:12.025 - device netclient.0 19:57:12.041 - dev: Created  netclient.0 0000000026F288C0
OK
19:57:12.041 - Api2BaseModule.GetAdditionInfo
19:57:13.616 - 
19:57:13.616 - 
19:57:13.616 - ########################### Mark of start channel changing.###########################
19:57:13.616 - 
19:57:13.616 - GraphManager::MainChangeChannel BE - EEN HD  1 provs
19:57:13.616 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
19:57:13.616 - SetChannelLogic::SetChannel 0
19:57:13.616 - SetChannelLogic::SetNormalChannel
19:57:13.616 - DevicesManager::CreateCloneDevice
19:57:13.616 - DevicesManager::CreateCloneDevice OK - founded in list
19:57:13.616 - TransponderManager
19:57:13.616 - CTransponderManager::InitOnNewDevice 0000000026F54818
19:57:13.616 - N -> Incoming Stream Cache
19:57:13.616 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
19:57:13.616 - Normal TsInputTrafficProcessor.
19:57:13.632 - FilterManager::InitOnNewDevice 1
19:57:13.632 - TS router+MarkerFinder -> InputTrafficProcessor
19:57:13.632 - Incoming Stream Cache -> TS router+MarkerFinder
19:57:13.632 - InputTrafficProcessor -> Router to timeshift
19:57:13.632 - InputTrafficProcessor(FM) -> FilterManager
19:57:13.632 - ModuleList::Free
19:57:13.632 - ChannelGraph5.CChannelGraph5
19:57:13.632 - TransponderManager::AddChildChannel
19:57:13.632 - TimeShift.TimeShift
19:57:13.632 - TimeShift.Create 200 mbytes 
19:57:13.632 - ts:One channel
19:57:13.632 - HugeMemoryBlocksManager::GetBlock 200 mbytes OK
19:57:13.632 - TransponderManager::CreateTimeshiftBuffer OK
19:57:13.663 - TimeShift.CreateReadingCursor
19:57:13.663 - TsInputTrafficProcessor.AddChannel 0
19:57:13.663 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
19:57:13.663 - ChannelGraph5.SetActiveState 1
19:57:13.663 - TransponderManager::SelectChannel
19:57:13.663 - TsInputTrafficProcessor.SelectChannel 0
19:57:13.663 - Api2BaseModule.UpdateAllOpenedChannels 0
19:57:13.663 - AV:UpdateAudioParamsAfterActive
19:57:13.663 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:13.663 - Graph::SetAudioChannelMask
19:57:13.663 - 
19:57:13.663 - TransponderManager::SetTransponder (CH) 0 BE - EEN HD
19:57:13.663 - *Transport TS
19:57:13.663 - Api2Device::SetChannel http://tektv.co:80/mick-order649/FahgNTyvQo/34981
19:57:13.679 - Api2Device::SetChannel result OK
19:57:13.679 - Api2BaseModule.OnTunerStateChanged Connecting
19:57:13.679 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
19:57:13.679 - TsInputTrafficProcessor.OpenTransponder 1
19:57:13.679 - FilterManager::OnSetTransponder SetChannel
19:57:13.679 - TimeShift.Reset
19:57:13.679 - TimeShift.Cursor reset
19:57:13.679 - ReaderCursor::Reset.. OK
19:57:13.679 - Prereader.Reset ...-OK
19:57:13.679 - Create EPGparser on SetChannel
19:57:13.679 - DvbEpgParser::SetChannel ID=69c80002.da1f26d2b963aefb.1
19:57:13.694 - ModuleList::ChangeChannel 0
19:57:13.694 - CChannelGraph5::StartChannel BE - EEN HD
19:57:13.694 - 
19:57:13.694 -        #################### Start stream. (0.078ms) #######################
19:57:13.694 - 
19:57:13.694 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=1
19:57:13.694 - TimeShift.Cursor SetChannel::v 0 (0)
19:57:13.694 - ReaderCursor::SetChannel.. OK
19:57:13.710 - ReaderCursor::SetPosition 0 (w=0)
19:57:13.710 - Prereader.SetChannel..
19:57:13.710 - Prereader.SetChannel unmask 1
19:57:13.710 - Plugin::SetChannel 1
19:57:13.710 - TransponderManager::SelectChannel
19:57:13.710 - TsInputTrafficProcessor.SelectChannel 1
19:57:13.710 - Api2BaseModule.UpdateAllOpenedChannels 1
19:57:13.710 - TsInputTrafficProcessor.UpdateChannel 1
19:57:13.710 - GraphState: Undef by StartChannelIsReady
19:57:13.710 - PreScanner.StartChannelIsReady net=1 parseall=1
19:57:13.710 - VideoMediaTypeDetection: h.264
19:57:13.710 - SetMarkerAudioLngStream Gen New stream
19:57:13.710 - SimpleTsDemux::Init apid=257, vpid=256
19:57:13.710 - GraphState: RequestPATScan by StartChannelIsReady TS
19:57:13.710 - Api2BaseModule.Filters_EnabledFullTS 1
19:57:13.710 - PlayTimePATParser.OpenParser 1
19:57:13.710 - Open TSTS filter pid=0 data=0
19:57:13.710 - TSTS cur size=0
19:57:13.710 - Graph::SetVideoRect {8 8 712 568}
19:57:13.710 - ChannelGraph5.SetActiveState 1
19:57:13.726 - Graph::SetVideoWindowRect {33 0 166 100}
19:57:13.726 - Graph::UpdateRenderMode
19:57:13.757 - GraphManager::MultiChannelRendering
19:57:13.757 - ChannelGraph5.SetActiveState 1
19:57:13.757 - Api2BaseModule.OnTunerStateChanged Preparing
19:57:13.781 - Graph::SetVideoWindowRect {40 0 452 309}
19:57:13.867 - OK. 102
19:57:13.902 - Api2BaseModule.OnTunerStateChanged Playing
19:57:14.089 - OSDManager::Window_Add
19:57:14.091 - OSDManager::Window_Show
19:57:14.121 - * s:0 q:0 tr=0 (0) Preparing
19:57:14.153 - OSDManager::Window_Show
19:57:14.158 - OSDManager::Window_Show
19:57:14.329 - Api2BaseModule.OnTunerStateChanged Connecting
19:57:14.361 - Api2BaseModule.OnTunerStateChanged Preparing
19:57:14.515 - Api2BaseModule.OnTunerStateChanged Playing
19:57:14.521 - 
19:57:14.522 -        #################### First traffic. (0.905ms) #######################
19:57:14.524 - 
19:57:14.525 - PlayTimePATParser.EndTask
19:57:14.526 - PreScanner.OnAfterScanPat count=2 {1;4096}
19:57:14.527 - Api2BaseModule.Filters_EnabledFullTS 0
19:57:14.526 - TransportMarker: TS
19:57:14.529 - GraphState: RequestPMTScan by OnAfterScanPat
19:57:14.531 - PlayTimePmtParser::OpenParser SID=1 PMT=4096 unknow channel=1
19:57:14.532 - Open TSTS filter pid=4096 data=2
19:57:14.530 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
19:57:14.534 - TSTS cur size=100392
19:57:14.535 - TSTS quick finish
19:57:14.536 - --SwitchEngineTransport TS > TS
19:57:14.539 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=209715200
19:57:14.542 - PreScanner.OnTransportMarker TS
19:57:14.545 - --SwitchEngineTransport end
19:57:14.547 - PreScanner.OnAfterScanPmt pmt=4096
19:57:14.549 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
19:57:14.551 - PlayTimePmtParser::StopParser
19:57:14.559 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=0
19:57:14.560 - TimeShift.Cursor SetChannel::v 214508 (214508)
19:57:14.560 - Prereader.SetChannel unmask 1
19:57:14.561 - Plugin::SetChannel 1
19:57:14.562 - TransponderManager::SelectChannel
19:57:14.563 - TsInputTrafficProcessor.SelectChannel 1
19:57:14.564 - Api2BaseModule.UpdateAllOpenedChannels 1
19:57:14.565 - TsInputTrafficProcessor.UpdateChannel 1
19:57:14.566 - PreScanner.StartChannelIsReady net=1 parseall=0
19:57:14.567 - LngDefaultSelector.try select audio pid=257 type=1 lng=0-(goh,goh,rus) l=99
19:57:14.568 - ChannelGraph5::AfterDetectChannelParams
19:57:14.569 - VideoMediaTypeDetection: h.264
19:57:14.570 - SimpleTsDemux::Init apid=257, vpid=256
19:57:14.570 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
19:57:14.571 - demux: ProgDemux
19:57:14.582 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
19:57:14.583 - Prereader::ReaderCursor.. OK
19:57:14.584 - ReaderCursor::SetPosition 0 (w=401004)
19:57:14.585 - Prereader.StartPlayback Push buffering=0 recv=000000002B0E4308 [StartChannelHelperBase(sl)]
19:57:14.586 - Prereader.StartPlayback after lock
19:57:14.587 - PreScanner.StartPmtParserIfNeed
19:57:14.588 - PlayTimePmtParser::OpenParser SID=1 PMT=4096 unknow channel=0
19:57:14.589 - Open TSTS filter pid=4096 data=2
19:57:14.589 - TSTS cur size=435596
19:57:14.587 - VideoParser::First packet 35846 h.264
19:57:14.591 - h264 detected 0 ms
19:57:14.592 - AudioParser::First packet 2890
19:57:14.594 - AudioParser::AAC detected
19:57:14.595 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
19:57:14.596 - PreScanner.Post_InitRender [ReceiveTraffic]
19:57:14.816 - OSDManager::Window_Show
19:57:14.817 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
19:57:14.818 - CalculBufferingSize prov=0000000026F55C94 marker=1 request=1
19:57:14.819 - CalculBuffering 1000ms br=2000kb to 250 kbytes. By type
19:57:14.820 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
19:57:14.820 - Prereader::ReaderCursor.. OK
19:57:14.822 - ReaderCursor::SetPosition 0 (w=2008028)
19:57:14.822 - Prereader.StartPlayback Pull buffering=256000 recv=0000000000000000 [EnablePullModeAndStartPlayback]
19:57:14.823 - Prereader.StartPlayback after lock
19:57:14.823 - Buffering/prereader size 250/3906 kbytes
19:57:14.824 - ------sGetVideoParams
19:57:14.826 - ChannelGraph5.CreateInterfacesAndRenderGraph
19:57:14.826 - * s:100 q:100 tr=2045816 (0) Preparing
19:57:14.854 - subsm.StartChannel demux=ProgDemux tr=TS
19:57:14.855 - subsm.RecreateSubtitlesParsers 000000002702B720 ts=1 subindex=-1
19:57:14.856 - subsm.Set Teletext parser 0
19:57:14.856 - subsm.RecreateSubtitlesParsers end
19:57:14.857 - subs: OnSetChannel
19:57:14.857 - CreateCustomGraph
19:57:14.858 - VideoType:H.264/AVC
19:57:14.859 - Video/4.H264 : MPEG2_VIDEO s={0,840,0,0}, t={0,840,0,0}, bmp=1920x1088, r=30/17, br=0,frametime=40ms, IF=0x0, CF=0x0)
19:57:14.871 - Add video codec 212690fb-83e5-4526-8fd7-74478b7939cd - OK
19:57:14.873 - CChannelGraph5::CreateRender 10
19:57:14.874 - VR: EVR Custom
19:57:14.874 - VR: create new render
19:57:15.083 - VR: Add video renderer Video Renderer - OK
19:57:15.085 - CreateAudioPartOfGraph
19:57:15.086 - AudioType:AAC/ADTS
19:57:15.087 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 44100 16bit formatTag= 0x1600)
19:57:15.092 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
19:57:15.108 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Primary Sound Driver OK
19:57:15.109 - AP: created
19:57:15.111 - Added audio processor
19:57:15.116 - Connect codec to AP - OK
19:57:15.124 - Add and connect audio renderer - OK
19:57:15.124 - Graph::AfterRender
19:57:15.125 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
19:57:15.126 - AV:UpdateAudioParamsAfterActive
19:57:15.126 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
19:57:15.127 - Graph::SetVolume 100% 0db
19:57:15.128 - Graph::SetAudioChannelMask
19:57:15.129 - AudioProcessor::SetAudioChannelsMask
19:57:15.130 - AudioProcessor::Equalizer_SetBands
19:57:15.130 - Graph::Run
19:57:15.579 - Graph::Run 1
19:57:15.580 - Graph::SetPictureParams
19:57:15.581 - Graph::SetVideoRect {24 16 1896 1076}
19:57:15.582 - Graph::SetVideoWindowRect {0 15 493 294}
19:57:15.583 - AfterRenderInit
19:57:15.585 - ARI
19:57:15.586 - CChannelGraph5::HideSubtitles
19:57:15.673 - 
19:57:15.675 - ########################### Mark of finish channel changing. (2.059ms) #############
19:57:15.676 - 
19:57:15.776 - * s:100 q:100 tr=6288976 (0) Playing
19:57:15.970 - 
19:57:15.971 - ########################### First video frame. (2.356ms) #############
19:57:15.971 - 
19:57:16.339 - * s:100 q:100 tr=3818468 (0) Playing
19:57:16.713 - changed ratio to 1920/1080
19:57:16.714 - Graph::SetVideoRect {24 16 1896 1076}
19:57:16.715 - Graph::SetVideoWindowRect {0 17 493 292}
19:57:17.073 - * s:100 q:100 tr=3397348 (0) Playing
19:57:17.594 - * s:100 q:100 tr=0 (0) Playing
19:57:18.373 - OSDManager::Window_Show
19:57:18.377 - OSDManager::Window_Delete
19:57:18.410 - * s:100 q:100 tr=261884 (0) Playing
19:57:19.382 - * s:100 q:100 tr=523768 (0) Playing
19:57:20.396 - * s:100 q:100 tr=523768 (0) Playing
19:57:21.410 - * s:100 q:100 tr=342160 (0) Playing
19:57:22.424 - * s:100 q:100 tr=523768 (0) Playing
19:57:23.657 - * s:100 q:100 tr=481092 (0) Playing
19:57:24.451 - * s:100 q:100 tr=304560 (0) Playing
19:57:25.465 - * s:100 q:100 tr=261884 (0) Playing
19:57:26.479 - * s:100 q:100 tr=523768 (0) Playing
19:57:27.518 - * s:100 q:100 tr=523768 (0) Playing
19:57:28.507 - * s:100 q:100 tr=261884 (0) Playing
19:57:29.521 - * s:100 q:100 tr=523768 (0) Playing
19:57:30.534 - * s:100 q:100 tr=523768 (0) Playing
19:57:31.549 - * s:100 q:100 tr=334452 (0) Playing
19:57:32.563 - * s:100 q:100 tr=523768 (0) Playing
19:57:33.582 - * s:100 q:100 tr=261884 (0) Playing
19:57:34.597 - * s:100 q:100 tr=523768 (0) Playing
19:57:35.606 - * s:100 q:100 tr=261884 (0) Playing
19:57:36.621 - * s:100 q:100 tr=540124 (0) Playing
19:57:37.634 - * s:100 q:100 tr=554036 (0) Playing
19:57:38.649 - * s:100 q:100 tr=530724 (0) Playing
19:57:39.662 - * s:100 q:100 tr=606112 (0) Playing
19:57:40.677 - * s:100 q:100 tr=539936 (0) Playing
19:57:41.690 - * s:100 q:100 tr=347612 (0) Playing
19:57:42.704 - * s:100 q:100 tr=555916 (0) Playing
19:57:43.718 - * s:100 q:100 tr=375624 (0) Playing
19:57:44.731 - * s:100 q:100 tr=669656 (0) Playing
19:57:45.745 - * s:100 q:100 tr=264328 (0) Playing
19:57:46.759 - * s:100 q:100 tr=523768 (0) Playing
19:57:47.773 - * s:100 q:100 tr=261884 (0) Playing
19:57:48.787 - * s:100 q:100 tr=523768 (0) Playing
19:57:49.801 - * s:100 q:100 tr=464548 (0) Playing
19:57:50.815 - * s:100 q:100 tr=523768 (0) Playing
19:57:51.830 - * s:100 q:100 tr=261884 (0) Playing
19:57:52.163 - 
19:57:52.164 - Stop engine
19:57:52.165 - DevicesManager::Stop
19:57:52.166 - ChannelGraph5::StopChannel 
19:57:52.166 - PreScanner.Stop
19:57:52.167 - GraphState: Undef by PreScanner.Stop
19:57:52.168 - PlayTimePmtParser::StopParser
19:57:52.168 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
19:57:52.169 - Prereader::ReaderCursor.. OK
19:57:52.170 - ReaderCursor::SetPosition 0 (w=30433628)
19:57:52.170 - Prereader.StartPlayback Disable buffering=0 recv=0000000000000000 [Engine5ChannelBase.StopChannel]
19:57:52.171 - Prereader.StartPlayback after lock
19:57:52.171 - subsm.StopChannel
19:57:52.172 - Graph::Stop
19:57:52.227 - Before Stop ...19:57:52.228 - OnGraphStop ...19:57:52.232 - VR: Disconnect
19:57:52.233 - Graph::ReleaseAllFilters
19:57:52.234 - release: Prog Audio renderer - RR - OK.
19:57:52.254 - release: Video Renderer - RR - OK.
19:57:52.271 - release: Microsoft DTV-DVD Video Decoder - RR - OK.
19:57:52.275 - release: Audio Processor - RR - OK.
19:57:52.276 - release: Microsoft DTV-DVD Audio Decoder - RR - OK.
19:57:52.278 - FilterGraph: 1
19:57:52.280 - ChannelGraph5.~CChannelGraph5
19:57:52.281 - PreScanner.Stop
19:57:52.281 - GraphState: Undef by PreScanner.Stop
19:57:52.282 - PlayTimePmtParser::StopParser
19:57:52.282 - subsm.StopChannel
19:57:52.283 - Graph::ReleaseAllFilters
19:57:52.288 - ChannelGraph5.~CChannelGraph5 finish
19:57:52.338 - TransponderManager::CloseChannel
19:57:52.338 - TsInputTrafficProcessor.RemoveChannel 1
19:57:52.338 - ~TransponderChannelSource
19:57:52.354 - TimeShift.DestroyReadingCursor
19:57:52.370 - Api2BaseModule.UpdateAllOpenedChannels 0
19:57:52.370 - Destroy TSTS filter pid=0
19:57:52.370 - PlayTimePmtParser::StopParser
19:57:52.370 - Destroy TSTS filter pid=4096
19:57:52.370 - DevicesManager::DestroyTransponderManager
19:57:52.370 - Api2Device::SetChannel - None
19:57:52.416 - Api2Device::SetChannel result OK
19:57:52.416 - N -> 0
19:57:52.416 - ~TransponderManager
19:57:52.416 - N -> 0
19:57:52.416 - TimeShift.~TimeShift
19:57:52.416 - HugeMemoryBlocksManager::Free
19:57:52.416 - ModuleList::Free
19:57:52.416 - DevicesManager::Free devices
19:57:52.416 - SDP:Destroy netclient.0 own=0000000000000000
19:57:52.416 - SDP:after destroy device
19:57:52.416 - DevicesManager::Free RC
19:57:52.416 - Engine stopped
19:57:52.619 - HugeMemoryBlocksManager::FreeAll
