﻿23:54:22.021 - Start logging...
23:54:22.162 - ExceptionFilter::Init. restart=1
23:54:22.943 - DevicesManager::EnableCISupport
23:54:22.943 - CAVManager::SetSettings
23:54:22.959 - DevicesManager::DestroyAllTransponderManager
23:54:23.005 - SetDefaultNullCodecs
23:54:23.052 - SetDefaultNullCodecs
23:54:23.084 - DevicesManager::Start
23:54:23.084 - AVManager::Init
23:54:23.121 - RemoveAllCreateBlank needBlank1
23:54:23.136 - add graph 0000017669811A90 101
23:54:23.136 - [101]OnChangeCurrentGraph2 -RemoveAllBlank
23:54:23.215 - VR: GUI
23:54:23.261 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:23.418 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:23.418 - AV:UpdateAudioParamsAfterActive
23:54:23.433 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:23.449 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:23.918 - remove graph idx=0 count=1
23:54:23.918 - DevicesManager::StartDevices...
23:54:23.933 - device netclient.0 23:54:23.949 - dev: Created  netclient.0 0000017669804850
OK
23:54:23.980 - Api2BaseModule.GetAdditionInfo
23:54:38.495 - 
23:54:38.495 - 
23:54:38.510 - ########################### Mark of start channel changing.###########################
23:54:38.526 - 
23:54:38.542 - GraphManager::MainChangeChannel Classica  1 provs
23:54:38.557 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:54:38.573 - SetChannelLogic::SetChannel 0
23:54:38.588 - SetChannelLogic::SetNormalChannel
23:54:38.604 - DevicesManager::CreateCloneDevice
23:54:38.604 - DevicesManager::CreateCloneDevice OK - founded in list
23:54:38.620 - TransponderManager
23:54:38.635 - CTransponderManager::InitOnNewDevice 0000017669870888
23:54:38.651 - SetOwner N -> Incoming Stream Cache
23:54:38.667 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
23:54:38.682 - Normal TsInputTrafficProcessor.
23:54:38.698 - FilterManager::InitOnNewDevice 1
23:54:38.713 - TS router+MarkerFinder -> InputTrafficProcessor
23:54:38.729 - Incoming Stream Cache -> TS router+MarkerFinder
23:54:38.745 - InputTrafficProcessor -> Router to timeshift
23:54:38.760 - InputTrafficProcessor(FM) -> FilterManager
23:54:38.776 - ModuleList::Free
23:54:38.792 - ChannelGraph5.CChannelGraph5
23:54:38.807 - TransponderManager::AddChildChannel
23:54:38.823 - TimeShift.TimeShift
23:54:38.838 - TimeShift.Create 400 mbytes 
23:54:38.854 - ts:One channel
23:54:38.870 - HugeMemoryBlocksManager::GetBlock 400 mbytes OK
23:54:38.885 - TransponderManager::CreateTimeshiftBuffer OK
23:54:38.901 - TimeShift.CreateReadingCursor
23:54:38.917 - TransponderChannelSource.Plugins 000001766AD92160 csa 0000000000000000
23:54:38.932 - TsInputTrafficProcessor.AddChannel 0
23:54:38.963 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
23:54:38.979 - add graph 000001766AEFF428 102
23:54:39.026 - ChannelGraph5.SetActiveState 1
23:54:39.026 - TransponderManager::SelectChannel
23:54:39.042 - TsInputTrafficProcessor.SelectChannel 0
23:54:39.042 - Api2BaseModule.UpdateAllOpenedChannels 0
23:54:39.057 - AV:UpdateAudioParamsAfterActive
23:54:39.073 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:39.088 - Graph::SetAudioChannelMask
23:54:39.104 - 
23:54:39.120 - TransponderManager::SetTransponder (CH) 0 Classica
23:54:39.135 - *Transport TS
23:54:39.151 - Api2Device::SetChannel http://am01.spr24.net/875/mpegts?token=4u6VY92quYvYVm
23:54:39.229 - Api2Device::SetChannel result OK
23:54:39.229 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:54:39.245 - TsInputTrafficProcessor.OpenTransponder 1
23:54:39.260 - Api2BaseModule.OnTunerStateChanged Connecting
23:54:39.276 - Api2BaseModule.OnTunerStateChanged  -3
23:54:39.260 - FilterManager::OnSetTransponder SetChannel
23:54:39.307 - TimeShift.Reset
23:54:39.323 - TimeShift.Cursor reset
23:54:39.323 - ReaderCursor::Reset.. OK
23:54:39.323 - Prereader.Reset ...-OK
23:54:39.338 - Create EPGparser on SetChannel
23:54:39.354 - DvbEpgParser::SetChannel ID=3cf00002.53d4941cfbc16ea7.abcd
23:54:39.385 - DvbEpgParser::mode normal pos=0
23:54:39.401 - ModuleList::ChangeChannel 0
23:54:39.417 - [102]ChannelGraph5::StartChannel Classica
23:54:39.432 - 
23:54:39.448 - [102]       #################### Start stream. (0.953ms) #######################
23:54:39.463 - 
23:54:39.510 - Api2BaseModule.OnTunerStateChanged Preparing
23:54:39.526 - Api2BaseModule.OnTunerStateChanged  -4
23:54:39.526 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=1
23:54:39.557 - TimeShift.Cursor SetChannel::v 0 (0)
23:54:39.573 - Api2BaseModule.OnTunerStateChanged Playing
23:54:39.588 - Api2BaseModule.OnTunerStateChanged  -4
23:54:39.573 - ReaderCursor::SetChannel.. OK
23:54:39.635 - ReaderCursor::SetPosition 0 (w=0)
23:54:39.651 - Prereader.SetChannel..
23:54:39.667 - Prereader.SetChannel unmask 1
23:54:39.682 - Plugin::SetChannel 43981
23:54:39.698 - TransponderManager::SelectChannel
23:54:39.713 - TsInputTrafficProcessor.SelectChannel 43981
23:54:39.729 - Api2BaseModule.UpdateAllOpenedChannels 1
23:54:39.745 - TsInputTrafficProcessor.UpdateChannel 43981
23:54:39.760 - GraphState: Undef by StartChannelIsReady
23:54:39.776 - PreScanner.StartChannelIsReady net=1 parseall=1
23:54:39.792 - VideoMediaTypeDetection: mpeg2
23:54:39.807 - SetMarkerAudioLngStream Gen New stream
23:54:39.823 - SimpleTsDemux::Init apid=-1, vpid=100
23:54:39.838 - GraphState: RequestPATScan by StartChannelIsReady TS
23:54:39.854 - Api2BaseModule.Filters_EnabledFullTS 1
23:54:39.870 - PlayTimePATParser.OpenParser 43981
23:54:39.885 - Open TSTS filter pid=0 data=0
23:54:39.901 - TSTS cur size=0
23:54:39.917 - ChannelGraph5.CreateGraphForOsdOnly
23:54:39.948 - CChannelGraph5::CreateRender 6
23:54:39.948 - Graph::SetVideoRect {8 8 712 568}
23:54:39.963 - [102]OnChangeCurrentGraph2 -endOfSetChannel
23:54:39.995 - ChannelGraph5.SetActiveState 1
23:54:40.088 - Graph::SetVideoWindowRect {33 0 166 100}
23:54:40.104 - Graph::UpdateRenderMode
23:54:40.166 - GraphManager::MultiChannelRendering
23:54:40.166 - ChannelGraph5.SetActiveState 1
23:54:40.198 - Graph::SetVideoWindowRect {246 0 1355 832}
23:54:40.432 - OK. 102
23:54:40.791 - Api2BaseModule.OnTunerStateChanged Playing
23:54:40.791 - Api2BaseModule.OnTunerStateChanged  -4
23:54:40.854 - Api2BaseModule.OnTunerStateChanged Connecting
23:54:40.854 - Api2BaseModule.OnTunerStateChanged  -3
23:54:40.932 - * s:0 q:0 tr=0 (0) Connecting
23:54:41.026 - Api2BaseModule.OnTunerStateChanged Preparing
23:54:41.026 - Api2BaseModule.OnTunerStateChanged  -4
23:54:41.120 - Api2BaseModule.OnTunerStateChanged Playing
23:54:41.120 - Api2BaseModule.OnTunerStateChanged  -4
23:54:41.182 - * s:0 q:0 tr=0 (0) Preparing
23:54:41.229 - TransportMarker: TS
23:54:41.229 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
23:54:41.245 - 
23:54:41.260 -        #################### First traffic. (2.766ms) #######################
23:54:41.276 - 
23:54:41.323 - --SwitchEngineTransport TS > TS
23:54:41.323 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
23:54:41.338 - PreScanner.OnTransportMarker TS
23:54:41.354 - --SwitchEngineTransport end
23:54:42.182 - * s:100 q:100 tr=564 (0) Preparing
23:54:43.182 - * s:100 q:100 tr=188 (0) Preparing
23:54:44.182 - * s:100 q:100 tr=188 (0) Preparing
23:54:45.182 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:46.182 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:47.182 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:48.182 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:49.182 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:50.181 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:51.197 - * s:100 q:100 tr=0 (0) PreparingButTooLong
23:54:52.197 - * s:100 q:100 tr=188 (0) PreparingButTooLong
23:54:53.103 - PlayTimePATParser.EndTask
23:54:53.103 - PreScanner.OnAfterScanPat count=2 {1;4095}
23:54:53.119 - Api2BaseModule.Filters_EnabledFullTS 0
23:54:53.134 - GraphState: RequestPMTScan by OnAfterScanPat
23:54:53.150 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
23:54:53.166 - Open TSTS filter pid=4095 data=2
23:54:53.181 - TSTS cur size=193076
23:54:53.197 - TSTS quick finish
23:54:53.212 - PreScanner.OnAfterScanPmt pmt=4095
23:54:53.228 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
23:54:53.244 - PlayTimePmtParser::StopParser
23:54:53.462 - TimeShift.Cursor SetChannel pidmask=0000000000000000 first=0
23:54:53.462 - TimeShift.Cursor SetChannel::v 274856 (274856)
23:54:53.478 - Prereader.SetChannel unmask 1
23:54:53.494 - Plugin::SetChannel 1
23:54:53.509 - TransponderManager::SelectChannel
23:54:53.525 - TsInputTrafficProcessor.SelectChannel 1
23:54:53.541 - Api2BaseModule.UpdateAllOpenedChannels 1
23:54:53.556 - TsInputTrafficProcessor.UpdateChannel 1
23:54:53.572 - PreScanner.StartChannelIsReady net=1 parseall=0
23:54:53.603 - LngDefaultSelector.try select audio pid=221 type=1 lng=7693668-deu(goh,goh,rus) l=99
23:54:53.619 - ChannelGraph5::AfterDetectChannelParams
23:54:53.634 - VideoMediaTypeDetection: h.264
23:54:53.650 - SimpleTsDemux::Init apid=221, vpid=211
23:54:53.666 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:54:53.681 - demux: ProgDemux
23:54:53.765 - CalcPositionMinusDeltaTime  0 000000 old 12s new 10s delta 2s result 0 001880
23:54:53.765 - TimeShift.Cursor PushMode 1 1 (pos=1) [StartChannelHelperBase(sl)]
23:54:53.785 - Prereader::ReaderCursor.. OK
23:54:53.819 - ReaderCursor::SetPosition 1880 (w=682252)
23:54:53.837 - Prereader.StartPlayback Push buffering=0 recv=000001766AF003D8 [StartChannelHelperBase(sl)]
23:54:53.854 - Prereader.StartPlayback after lock
23:54:53.872 - PreScanner.StartPmtParserIfNeed
23:54:53.889 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
23:54:53.907 - Open TSTS filter pid=4095 data=2
23:54:53.926 - TSTS cur size=744292
23:54:53.872 - VideoParser::First packet 72844 h.264
23:54:53.963 - h264 detected 0 ms
23:54:53.965 - AudioParser::First packet 1536
23:54:53.966 - AudioParser::packet 1536
23:54:53.979 - AudioParser::AC3 detected
23:54:53.980 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:54:53.982 - PreScanner.Post_InitRender [ReceiveTraffic]
23:54:53.997 - [102]sm: TvInitRenderer 1
23:54:54.015 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
23:54:54.032 - CalculBufferingSize prov=0000017669871D04 marker=1 request=1
23:54:54.049 - CalculBuffering 3000ms br=2000kb to 750 kbytes. By type
23:54:54.065 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:54:54.082 - Prereader::ReaderCursor.. OK
23:54:54.115 - ReaderCursor::SetPosition 0 (w=979480)
23:54:54.136 - Prereader.StartPlayback Pull buffering=768000 recv=0000000000000000 [EnablePullModeAndStartPlayback]
23:54:54.155 - Prereader.StartPlayback after lock
23:54:54.175 - Buffering/prereader size 750/3906 kbytes
23:54:54.195 - ------sGetVideoParams
23:54:54.217 - ChannelGraph5.CreateInterfacesAndRenderGraph
23:54:54.219 - * s:100 q:100 tr=985308 (0) Preparing
23:54:54.252 - OnBuffering.OnTunerStateChanged  4
23:54:54.234 - subsm.StartChannel demux=ProgDemux tr=TS
23:54:54.285 - subsm.RecreateSubtitlesParsers 000001766AD6F5F0 ts=1 subindex=1
23:54:54.302 - subsm.Set Teletext parser 0
23:54:54.327 - OnBuffering.OnTunerStateChanged  0
23:54:54.339 - subsm.Set Subtitle parser 1025
23:54:54.357 - SSBitmap: SetChannel
23:54:54.376 - subsm.RecreateSubtitlesParsers end
23:54:54.401 - subs: OnSetChannel
23:54:54.420 - CreateCustomGraph
23:54:54.438 - VideoType:H.264/AVC ffmpeg=0
23:54:54.454 - 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)
23:54:54.473 - Add video codec ee30215d-164f-4a92-a4eb-9d4c13390f9f
23:54:54.596 - OK
23:54:54.597 - CChannelGraph5::CreateRender 6
23:54:54.617 - VR: EVR
23:54:54.636 - VR: create new render
23:54:54.761 - VR: Add video renderer Video Renderer - OK
23:54:54.761 - CreateAudioPartOfGraph
23:54:54.780 - AudioType:AC3 
23:54:54.797 - Audio/AC3 : WaveFormatEx (6ch 48000 0bit formatTag= 0x0)
23:54:54.816 - Add audio codec 4a2286e0-7bef-11ce-9bd9-0000e202599c - Pin for connection not found! 0x80040207
23:54:54.853 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Первичный звуковой драйвер OK (AC3)
23:54:54.854 - Added audio renderer
23:54:55.016 - Try to found any codec before renderer... OK.
23:54:55.017 - Graph::AfterRender
23:54:55.047 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
23:54:55.063 - AV:UpdateAudioParamsAfterActive
23:54:55.080 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:54:55.098 - Graph::SetVolume 59% -951db
23:54:55.118 - Graph::SetAudioChannelMask
23:54:55.133 - Graph::Run
23:54:55.347 - Graph::Run 1
23:54:55.348 - Graph::SetPictureParams
23:54:55.365 - Graph::SetVideoRect {24 16 1896 1068}
23:54:55.394 - Graph::SetVideoWindowRect {66 0 1534 832}
23:54:55.404 - * s:100 q:100 tr=894692 (0) Playing
23:54:55.435 - OnBuffering.OnTunerStateChanged  7
23:54:55.485 - 
23:54:55.486 - [0]########################### Mark of finish channel changing. (16.984ms) #############
23:54:55.487 - 
23:54:55.533 - OnBuffering.OnTunerStateChanged  0
23:54:55.821 - CChannelGraph5::HideSubtitles
23:54:55.918 - Graph::OnVideoWindowPositionChanged
23:54:56.034 - * s:53 q:100 tr=495756 (0) Buffering
23:54:56.658 - * s:100 q:100 tr=464736 (0) Playing
23:54:56.732 - OnBuffering.OnTunerStateChanged  7
23:54:57.246 - * s:100 q:100 tr=371864 (0) Playing
23:54:58.267 - * s:100 q:100 tr=857092 (0) Playing
23:54:59.258 - * s:100 q:100 tr=844120 (0) Playing
23:55:00.264 - * s:100 q:100 tr=731696 (0) Playing
23:55:01.248 - * s:100 q:100 tr=863108 (0) Playing
23:55:02.271 - * s:100 q:100 tr=740720 (0) Playing
23:55:03.270 - * s:100 q:100 tr=761588 (0) Playing
23:55:11.018 - * s:100 q:100 tr=5998328 (0) Playing
23:55:11.571 - * s:100 q:100 tr=380324 (0) Playing
23:55:12.280 - * s:100 q:100 tr=624160 (0) Playing
23:55:13.273 - * s:100 q:100 tr=900708 (0) Playing
23:55:14.288 - * s:100 q:100 tr=790728 (0) Playing
23:55:25.399 - Graph::OnVideoWindowPositionChanged
23:55:25.519 - * s:100 q:100 tr=8818140 (0) Playing
23:55:26.318 - * s:100 q:100 tr=639952 (0) Playing
23:55:27.677 - * s:100 q:100 tr=1018020 (0) Playing
23:55:28.321 - * s:100 q:100 tr=543320 (0) Playing
23:55:29.311 - * s:100 q:100 tr=818740 (0) Playing
23:55:34.473 - * s:100 q:100 tr=4204432 (0) Playing
23:55:35.317 - * s:100 q:100 tr=641644 (0) Playing
23:55:39.575 - * s:100 q:100 tr=3406372 (0) Playing
23:55:40.347 - * s:100 q:100 tr=617204 (0) Playing
23:55:41.359 - * s:100 q:100 tr=823628 (0) Playing
23:55:47.631 - Graph::OnVideoWindowPositionChanged
23:55:47.795 - * s:100 q:100 tr=5369656 (0) Playing
23:55:48.378 - * s:100 q:100 tr=508916 (0) Playing
23:55:49.381 - * s:100 q:100 tr=749180 (0) Playing
23:55:50.389 - * s:100 q:100 tr=845060 (0) Playing
23:55:57.220 - Graph::OnVideoWindowPositionChanged
23:55:59.106 - * s:100 q:100 tr=7223712 (0) Playing
23:56:13.636 - ChannelGraph5.~CChannelGraph5
23:56:13.637 - Graph::Stop
23:56:39.151 - Before Stop ...23:56:39.170 - OnGraphStop ...23:56:39.327 - PreScanner.Stop
23:56:39.328 - GraphState: Undef by PreScanner.Stop
23:56:39.352 - PlayTimePmtParser::StopParser
23:56:39.368 - subsm.StopChannel
23:56:39.401 - SSBitmap: SetChannel
23:56:39.402 - VR: Disconnect
23:56:39.454 - Graph::ReleaseAllFilters
23:56:39.454 - release: Prog Audio renderer - RR - OK.
23:56:39.691 - release: Video Renderer - RR - OK.
23:56:39.753 - release: Video decoder  - RR - OK.
23:56:39.832 - release: LAV Audio Decoder - RR - OK.
23:56:39.979 - FilterGraph: 1
23:56:40.061 - ChannelGraph5.~CChannelGraph5 finish
23:56:40.061 - TransponderManager::CloseChannel
23:56:40.080 - TsInputTrafficProcessor.RemoveChannel 1
23:56:40.101 - ~TransponderChannelSource
23:56:40.262 - TimeShift.DestroyReadingCursor
23:56:40.282 - Api2BaseModule.UpdateAllOpenedChannels 0
23:56:40.282 - Destroy TSTS filter pid=0
23:56:40.302 - PlayTimePmtParser::StopParser
23:56:40.312 - Destroy TSTS filter pid=4095
23:56:40.332 - DevicesManager::DestroyTransponderManager
23:56:40.352 - Api2Device::SetChannel - None
23:56:40.464 - Api2Device::SetChannel result OK
23:56:40.464 - SetOwner N -> 0
23:56:40.474 - SetOwner SetTransponder null
23:56:40.494 - ~TransponderManager
23:56:40.524 - SetOwner N -> 0
23:56:40.544 - SetOwner SetTransponder null
23:56:40.605 - TimeShift.~TimeShift
23:56:40.605 - HugeMemoryBlocksManager::Free
23:56:40.625 - ModuleList::Free
23:56:40.635 - remove graph idx=0 count=1
23:56:40.663 - add graph 000001760D7D5000 103
23:56:40.673 - [103]OnChangeCurrentGraph2 -CloseGraph
23:56:40.754 - VR: GUI
23:56:40.796 - GraphManager::MultiChannelRendering
23:56:41.098 - remove graph idx=0 count=1
23:56:41.108 - add graph 000001760D7D4A00 104
23:56:41.159 - [104]OnChangeCurrentGraph2 -CloseGraph
23:56:41.320 - VR: GUI
23:56:41.390 - GraphManager::MultiChannelRendering
23:56:41.711 - remove graph idx=0 count=1
23:56:41.733 - add graph 000001760D7D4C40 105
23:56:41.793 - [105]OnChangeCurrentGraph2 -CloseGraph
23:56:41.905 - VR: GUI
23:56:41.975 - GraphManager::MultiChannelRendering
23:56:42.429 - 
23:56:42.429 - Stop engine
23:56:42.462 - DevicesManager::Stop
23:56:42.482 - remove graph idx=0 count=1
23:56:42.512 - DevicesManager::Free devices
23:56:42.531 - SDP:Destroy netclient.0 own=0000000000000000
23:56:42.563 - SDP:after destroy device
23:56:42.563 - DevicesManager::Free RC
23:56:42.583 - Engine stopped
23:56:42.664 - 
23:56:42.674 - Stop engine
23:56:42.714 - DevicesManager::Stop
23:56:42.734 - DevicesManager::Free devices
23:56:42.752 - Engine stopped
23:56:43.461 - HugeMemoryBlocksManager::FreeAll
