﻿17:57:00.502 - Start logging...
17:57:00.676 - ExceptionFilter::Init. restart=1
17:57:01.776 - DevicesManager::EnableCISupport
17:57:01.776 - CAVManager::SetSettings
17:57:01.798 - DevicesManager::DestroyAllTransponderManager
17:57:01.924 - SetDefaultNullCodecs
17:57:01.983 - SetDefaultNullCodecs
17:57:02.051 - DevicesManager::Start
17:57:02.052 - AVManager::Init
17:57:02.067 - RemoveAllCreateBlank needBlank1
17:57:02.091 - add graph 000001AB3D95EA20 101
17:57:02.099 - [101]OnChangeCurrentGraph2 -RemoveAllBlank
17:57:02.195 - VR: GUI
17:57:02.291 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:02.549 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:02.550 - AV:UpdateAudioParamsAfterActive
17:57:02.594 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:02.627 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:03.246 - remove graph idx=0 count=1
17:57:03.247 - DevicesManager::StartDevices...
17:57:03.266 - device netclient.0 17:57:03.322 - dev: Created  netclient.0 000001AB3D92C020
OK
17:57:03.386 - Api2BaseModule.GetAdditionInfo
17:57:41.481 - 
17:57:41.482 - 
17:57:41.518 - ########################### Mark of start channel changing.###########################
17:57:41.535 - 
17:57:41.557 - GraphManager::MainChangeChannel Fox Russia  1 provs
17:57:41.576 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
17:57:41.598 - SetChannelLogic::SetChannel 0
17:57:41.619 - SetChannelLogic::SetNormalChannel
17:57:41.636 - DevicesManager::CreateCloneDevice
17:57:41.660 - DevicesManager::CreateCloneDevice OK - founded in list
17:57:41.677 - TransponderManager
17:57:41.695 - CTransponderManager::InitOnNewDevice 000001AB3D991F38
17:57:41.717 - SetOwner N -> Incoming Stream Cache
17:57:41.737 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
17:57:41.753 - Normal TsInputTrafficProcessor.
17:57:41.770 - FilterManager::InitOnNewDevice 1
17:57:41.788 - TS router+MarkerFinder -> InputTrafficProcessor
17:57:41.804 - Incoming Stream Cache -> TS router+MarkerFinder
17:57:41.820 - InputTrafficProcessor -> Router to timeshift
17:57:41.836 - InputTrafficProcessor(FM) -> FilterManager
17:57:41.838 - ModuleList::Free
17:57:41.862 - ChannelGraph5.CChannelGraph5
17:57:41.898 - TransponderManager::AddChildChannel
17:57:41.914 - TimeShift.TimeShift
17:57:41.930 - TimeShift.Create 400 mbytes 
17:57:41.947 - ts:One channel
17:57:41.949 - HugeMemoryBlocksManager::GetBlock 400 mbytes OK
17:57:41.950 - TransponderManager::CreateTimeshiftBuffer OK
17:57:41.954 - TimeShift.CreateReadingCursor
17:57:41.957 - TransponderChannelSource.Plugins 000001AB21EFB8E0 csa 0000000000000000
17:57:41.981 - TsInputTrafficProcessor.AddChannel 0
17:57:41.992 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
17:57:42.003 - add graph 000001AB40FDE268 102
17:57:42.039 - ChannelGraph5.SetActiveState 1
17:57:42.040 - TransponderManager::SelectChannel
17:57:42.056 - TsInputTrafficProcessor.SelectChannel 0
17:57:42.073 - Api2BaseModule.UpdateAllOpenedChannels 0
17:57:42.093 - AV:UpdateAudioParamsAfterActive
17:57:42.116 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:42.132 - Graph::SetAudioChannelMask
17:57:42.166 - 
17:57:42.182 - TransponderManager::SetTransponder (CH) 0 Fox Russia
17:57:42.199 - *Transport TS
17:57:42.200 - Api2Device::SetChannel http://am01.spr24.net/5266/mpegts?token=4u6VY92quYvYVm
17:57:42.285 - Api2Device::SetChannel result OK
17:57:42.285 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
17:57:42.302 - TsInputTrafficProcessor.OpenTransponder 1
17:57:42.304 - Api2BaseModule.OnTunerStateChanged Connecting
17:57:42.338 - Api2BaseModule.OnTunerStateChanged  -3
17:57:42.319 - FilterManager::OnSetTransponder SetChannel
17:57:42.373 - TimeShift.Reset
17:57:42.390 - TimeShift.Cursor reset
17:57:42.407 - ReaderCursor::Reset.. OK
17:57:42.442 - Prereader.Reset ...-OK
17:57:42.476 - Create EPGparser on SetChannel
17:57:42.513 - DvbEpgParser::SetChannel ID=3cf00002.249b8dff88937d97.abcd
17:57:42.534 - DvbEpgParser::mode normal pos=0
17:57:42.537 - ModuleList::ChangeChannel 0
17:57:42.554 - [102]ChannelGraph5::StartChannel Fox Russia
17:57:42.571 - 
17:57:42.588 - [102]       #################### Start stream. (1.094ms) #######################
17:57:42.604 - 
17:57:42.666 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=1
17:57:42.666 - TimeShift.Cursor SetChannel::v 0 (0)
17:57:42.683 - ReaderCursor::SetChannel.. OK
17:57:42.716 - ReaderCursor::SetPosition 0 (w=0)
17:57:42.718 - Prereader.SetChannel..
17:57:42.719 - Prereader.SetChannel unmask 1
17:57:42.721 - Plugin::SetChannel 43981
17:57:42.722 - TransponderManager::SelectChannel
17:57:42.723 - TsInputTrafficProcessor.SelectChannel 43981
17:57:42.724 - Api2BaseModule.UpdateAllOpenedChannels 1
17:57:42.726 - TsInputTrafficProcessor.UpdateChannel 43981
17:57:42.727 - GraphState: Undef by StartChannelIsReady
17:57:42.728 - PreScanner.StartChannelIsReady net=1 parseall=1
17:57:42.730 - VideoMediaTypeDetection: mpeg2
17:57:42.731 - SetMarkerAudioLngStream Gen New stream
17:57:42.732 - SimpleTsDemux::Init apid=-1, vpid=100
17:57:42.733 - GraphState: RequestPATScan by StartChannelIsReady TS
17:57:42.734 - Api2BaseModule.Filters_EnabledFullTS 1
17:57:42.736 - PlayTimePATParser.OpenParser 43981
17:57:42.737 - Open TSTS filter pid=0 data=0
17:57:42.739 - TSTS cur size=0
17:57:42.740 - ChannelGraph5.CreateGraphForOsdOnly
17:57:42.764 - Api2BaseModule.OnTunerStateChanged Preparing
17:57:42.800 - Api2BaseModule.OnTunerStateChanged  -4
17:57:42.814 - CChannelGraph5::CreateRender 6
17:57:42.822 - Graph::SetVideoRect {8 8 712 568}
17:57:42.840 - [102]OnChangeCurrentGraph2 -endOfSetChannel
17:57:42.836 - Api2BaseModule.OnTunerStateChanged Playing
17:57:42.875 - Api2BaseModule.OnTunerStateChanged  -4
17:57:42.858 - ChannelGraph5.SetActiveState 1
17:57:42.983 - Graph::SetVideoWindowRect {33 0 166 100}
17:57:42.984 - Graph::UpdateRenderMode
17:57:43.081 - GraphManager::MultiChannelRendering
17:57:43.082 - ChannelGraph5.SetActiveState 1
17:57:43.127 - Graph::SetVideoWindowRect {246 0 1355 832}
17:57:43.364 - OK. 102
17:57:43.804 - Api2BaseModule.OnTunerStateChanged Playing
17:57:43.805 - Api2BaseModule.OnTunerStateChanged  -4
17:57:43.854 - Api2BaseModule.OnTunerStateChanged Connecting
17:57:43.855 - Api2BaseModule.OnTunerStateChanged  -3
17:57:43.861 - * s:0 q:0 tr=0 (0) Connecting
17:57:44.125 - Api2BaseModule.OnTunerStateChanged Preparing
17:57:44.125 - Api2BaseModule.OnTunerStateChanged  -4
17:57:44.207 - Api2BaseModule.OnTunerStateChanged Playing
17:57:44.229 - Api2BaseModule.OnTunerStateChanged  -4
17:57:44.286 - 
17:57:44.303 -        #################### First traffic. (2.812ms) #######################
17:57:44.321 - 
17:57:44.342 - TransportMarker: TS
17:57:44.370 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
17:57:44.415 - --SwitchEngineTransport TS > TS
17:57:44.415 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
17:57:44.439 - PreScanner.OnTransportMarker TS
17:57:44.466 - --SwitchEngineTransport end
17:57:45.071 - * s:100 q:100 tr=376 (0) Preparing
17:57:46.073 - * s:0 q:0 tr=0 (0) Preparing
17:57:47.081 - * s:100 q:100 tr=188 (0) Preparing
17:57:48.079 - * s:100 q:100 tr=188 (0) PreparingButTooLong
17:57:48.433 - PlayTimePATParser.EndTask
17:57:48.434 - PreScanner.OnAfterScanPat count=2 {1;4095}
17:57:48.456 - Api2BaseModule.Filters_EnabledFullTS 0
17:57:48.473 - GraphState: RequestPMTScan by OnAfterScanPat
17:57:48.489 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
17:57:48.507 - Open TSTS filter pid=4095 data=2
17:57:48.524 - TSTS cur size=67492
17:57:48.542 - TSTS quick finish
17:57:48.544 - PreScanner.OnAfterScanPmt pmt=4095
17:57:48.546 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
17:57:48.570 - PlayTimePmtParser::StopParser
17:57:48.819 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=0
17:57:48.820 - TimeShift.Cursor SetChannel::v 122200 (122200)
17:57:48.837 - Prereader.SetChannel unmask 1
17:57:48.853 - Plugin::SetChannel 1
17:57:48.870 - TransponderManager::SelectChannel
17:57:48.886 - TsInputTrafficProcessor.SelectChannel 1
17:57:48.903 - Api2BaseModule.UpdateAllOpenedChannels 1
17:57:48.921 - TsInputTrafficProcessor.UpdateChannel 1
17:57:48.922 - PreScanner.StartChannelIsReady net=1 parseall=0
17:57:48.923 - LngDefaultSelector.try select audio pid=221 type=1 lng=7566706-rus(goh,goh,rus) l=10
17:57:48.924 - LngDefaultSelector.try select audio pid=222 type=1 lng=6778469-eng(goh,goh,rus) l=99
17:57:48.926 - LngDefaultSelector. selected audio pid=221
17:57:48.927 - ChannelGraph5::AfterDetectChannelParams
17:57:48.929 - VideoMediaTypeDetection: h.264
17:57:48.953 - SimpleTsDemux::Init apid=221, vpid=211
17:57:48.970 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
17:57:48.987 - demux: ProgDemux
17:57:49.064 - CalcPositionMinusDeltaTime  0 000000 old 4s new 2s delta 2s result 0 000376
17:57:49.064 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
17:57:49.081 - Prereader::ReaderCursor.. OK
17:57:49.115 - ReaderCursor::SetPosition 376 (w=173148)
17:57:49.134 - Prereader.StartPlayback Push buffering=0 recv=000001AB40FDF218 [StartChannelHelperBase(sl)]
17:57:49.151 - Prereader.StartPlayback after lock
17:57:49.169 - AudioParser::First packet 331
17:57:49.187 - VideoParser::First packet 34612 h.264
17:57:49.204 - h264 detected 0 ms
17:57:49.206 - AudioParser::packet 351
17:57:49.208 - AudioParser::AAC detected
17:57:49.211 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
17:57:49.214 - PreScanner.Post_InitRender [ReceiveTraffic]
17:57:49.170 - PreScanner.StartPmtParserIfNeed
17:57:49.256 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
17:57:49.273 - Open TSTS filter pid=4095 data=2
17:57:49.290 - TSTS cur size=221652
17:57:49.426 - [102]sm: TvInitRenderer 1
17:57:49.427 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
17:57:49.445 - CalculBufferingSize prov=000001AB3D9933B4 marker=1 request=1
17:57:49.463 - CalculBuffering 3000ms br=2000kb to 750 kbytes. By type
17:57:49.481 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
17:57:49.500 - Prereader::ReaderCursor.. OK
17:57:49.540 - ReaderCursor::SetPosition 0 (w=274480)
17:57:49.558 - Prereader.StartPlayback Pull buffering=768000 recv=0000000000000000 [EnablePullModeAndStartPlayback]
17:57:49.575 - Prereader.StartPlayback after lock
17:57:49.593 - Buffering/prereader size 750/3906 kbytes
17:57:49.610 - ------sGetVideoParams
17:57:49.629 - ChannelGraph5.CreateInterfacesAndRenderGraph
17:57:49.651 - subsm.StartChannel demux=ProgDemux tr=TS
17:57:49.652 - subsm.RecreateSubtitlesParsers 000001AB3DAA6080 ts=1 subindex=-1
17:57:49.670 - subsm.Set Teletext parser 0
17:57:49.687 - subsm.RecreateSubtitlesParsers end
17:57:49.704 - subs: OnSetChannel
17:57:49.722 - CreateCustomGraph
17:57:49.738 - VideoType:H.264/AVC ffmpeg=0
17:57:49.756 - Video/4.H264 : MPEG2_VIDEO s={0,144,0,0}, t={0,144,0,0}, bmp=720x576, r=254/139, br=0,frametime=40ms, IF=0x0, CF=0x0)
17:57:49.777 - Add video codec ee30215d-164f-4a92-a4eb-9d4c13390f9f
17:57:50.163 - OK
17:57:50.163 - CChannelGraph5::CreateRender 6
17:57:50.188 - VR: EVR
17:57:50.205 - VR: create new render
17:57:50.587 - VR: Add video renderer Video Renderer - OK
17:57:50.587 - CreateAudioPartOfGraph
17:57:50.606 - AudioType:AAC/ADTS 
17:57:50.610 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 48000 16bit formatTag= 0x1600)
17:57:50.872 - Add audio codec e8e73b6b-4cb3-44a4-be99-4f7bcb96e491 - OK
17:57:50.932 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Panasonic-TV (Аудио Intel(R) для дисплеев) OK (normal)
17:57:50.932 - Added audio renderer
17:57:51.047 - Add and connect audio renderer - OK
17:57:51.047 - Graph::AfterRender
17:57:51.075 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
17:57:51.089 - AV:UpdateAudioParamsAfterActive
17:57:51.106 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
17:57:51.124 - Graph::SetVolume 45% -1440db
17:57:51.131 - Graph::SetAudioChannelMask
17:57:51.155 - Graph::Run
17:57:51.379 - Graph::Run 1
17:57:51.380 - Graph::SetPictureParams
17:57:51.400 - Graph::SetVideoRect {8 8 712 568}
17:57:51.445 - Graph::SetVideoWindowRect {40 0 1560 832}
17:57:51.502 - * s:100 q:100 tr=777004 (0) Playing
17:57:51.527 - OnBuffering.OnTunerStateChanged  0
17:57:51.567 - OnBuffering.OnTunerStateChanged  7
17:57:51.621 - 
17:57:51.625 - [0]########################### Mark of finish channel changing. (10.141ms) #############
17:57:51.645 - 
17:57:51.704 - CChannelGraph5::HideSubtitles
17:57:51.767 - Graph::OnVideoWindowPositionChanged
17:57:52.209 - * s:19 q:100 tr=153220 (0) Buffering
17:57:54.467 - * s:100 q:100 tr=620024 (0) Playing
17:57:54.467 - OnBuffering.OnTunerStateChanged  7
17:57:55.017 - * s:0 q:100 tr=147204 (0) Buffering
17:57:55.038 - OnBuffering.OnTunerStateChanged  6
17:57:58.858 - * s:100 q:100 tr=816672 (0) Playing
17:57:58.858 - OnBuffering.OnTunerStateChanged  7
17:57:59.788 - * s:100 q:100 tr=307756 (0) Playing
17:58:12.118 - * s:100 q:100 tr=3086396 (0) Playing
17:58:13.180 - * s:100 q:100 tr=257184 (0) Playing
17:58:14.176 - * s:100 q:100 tr=196648 (0) Playing
17:58:15.183 - * s:100 q:100 tr=261508 (0) Playing
17:58:16.199 - * s:100 q:100 tr=197964 (0) Playing
17:58:30.381 - * s:100 q:100 tr=3236796 (0) Playing
17:58:31.303 - * s:100 q:100 tr=224472 (0) Playing
17:58:32.314 - * s:100 q:100 tr=271848 (0) Playing
17:58:33.307 - * s:100 q:100 tr=259816 (0) Playing
17:58:34.322 - * s:100 q:100 tr=235000 (0) Playing
17:58:35.321 - * s:100 q:100 tr=245340 (0) Playing
17:58:36.362 - * s:100 q:100 tr=263388 (0) Playing
17:58:37.322 - * s:100 q:100 tr=202100 (0) Playing
17:58:38.333 - * s:100 q:100 tr=239136 (0) Playing
17:58:39.335 - * s:100 q:100 tr=124832 (0) Playing
17:58:40.351 - * s:100 q:100 tr=343100 (0) Playing
17:58:41.359 - * s:100 q:100 tr=305876 (0) Playing
17:58:42.352 - * s:100 q:100 tr=299672 (0) Playing
17:58:43.370 - * s:100 q:100 tr=205108 (0) Playing
17:58:44.385 - * s:100 q:100 tr=259628 (0) Playing
17:58:45.383 - * s:100 q:100 tr=203416 (0) Playing
17:58:46.363 - * s:100 q:100 tr=254364 (0) Playing
17:58:47.369 - * s:100 q:100 tr=299296 (0) Playing
17:58:50.032 - * s:100 q:100 tr=613444 (0) Playing
17:58:51.400 - * s:100 q:100 tr=306816 (0) Playing
17:58:57.842 - Graph::OnVideoWindowPositionChanged
17:58:58.344 - * s:100 q:100 tr=1662484 (0) Playing
17:58:59.414 - * s:100 q:100 tr=302116 (0) Playing
17:59:02.673 - ChannelGraph5.~CChannelGraph5
17:59:02.673 - Graph::Stop
17:59:14.576 - Before Stop ...17:59:14.609 - OnGraphStop ...17:59:14.661 - PreScanner.Stop
17:59:14.665 - GraphState: Undef by PreScanner.Stop
17:59:14.685 - PlayTimePmtParser::StopParser
17:59:14.703 - subsm.StopChannel
17:59:14.721 - VR: Disconnect
17:59:14.783 - Graph::ReleaseAllFilters
17:59:14.787 - release: Prog Audio renderer - RR - OK.
17:59:14.819 - release: Video Renderer - RR - OK.
17:59:14.875 - release: Video decoder  - RR - OK.
17:59:15.068 - release: Audio decoder - RR - OK.
17:59:15.290 - FilterGraph: 1
17:59:15.422 - ChannelGraph5.~CChannelGraph5 finish
17:59:15.423 - TransponderManager::CloseChannel
17:59:15.449 - TsInputTrafficProcessor.RemoveChannel 1
17:59:15.477 - ~TransponderChannelSource
17:59:15.681 - TimeShift.DestroyReadingCursor
17:59:15.683 - Api2BaseModule.UpdateAllOpenedChannels 0
17:59:15.706 - Destroy TSTS filter pid=0
17:59:15.730 - PlayTimePmtParser::StopParser
17:59:15.751 - Destroy TSTS filter pid=4095
17:59:15.770 - DevicesManager::DestroyTransponderManager
17:59:15.793 - Api2Device::SetChannel - None
17:59:15.982 - Api2Device::SetChannel result OK
17:59:15.982 - SetOwner N -> 0
17:59:16.001 - SetOwner SetTransponder null
17:59:16.018 - ~TransponderManager
17:59:16.035 - SetOwner N -> 0
17:59:16.055 - SetOwner SetTransponder null
17:59:16.096 - TimeShift.~TimeShift
17:59:16.096 - HugeMemoryBlocksManager::Free
17:59:16.114 - ModuleList::Free
17:59:16.131 - remove graph idx=0 count=1
17:59:16.156 - add graph 000001AB5D845BD0 103
17:59:16.166 - [103]OnChangeCurrentGraph2 -CloseGraph
17:59:16.265 - VR: GUI
17:59:16.334 - GraphManager::MultiChannelRendering
18:00:31.106 - 
18:00:31.106 - Stop engine
18:00:31.127 - DevicesManager::Stop
18:00:31.150 - remove graph idx=0 count=1
18:00:31.169 - DevicesManager::Free devices
18:00:31.170 - SDP:Destroy netclient.0 own=0000000000000000
18:00:31.209 - SDP:after destroy device
18:00:31.210 - DevicesManager::Free RC
18:00:31.228 - Engine stopped
18:00:31.297 - 
18:00:31.297 - Stop engine
18:00:31.330 - DevicesManager::Stop
18:00:31.348 - DevicesManager::Free devices
18:00:31.365 - Engine stopped
18:00:31.817 - HugeMemoryBlocksManager::FreeAll
