﻿01:22:07.893 - Start logging...
01:22:08.018 - ExceptionFilter::Init. restart=1
01:22:08.736 - DevicesManager::EnableCISupport
01:22:08.736 - CAVManager::SetSettings
01:22:08.752 - DevicesManager::DestroyAllTransponderManager
01:22:08.814 - SetDefaultNullCodecs
01:22:08.861 - SetDefaultNullCodecs
01:22:08.912 - DevicesManager::Start
01:22:08.912 - AVManager::Init
01:22:08.927 - RemoveAllCreateBlank needBlank1
01:22:08.943 - [101]OnChangeCurrentGraph2 -RemoveAllBlank
01:22:09.010 - VR: GUI
01:22:09.057 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:09.244 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:09.260 - AV:UpdateAudioParamsAfterActive
01:22:09.275 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:09.291 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:09.932 - DevicesManager::StartDevices...
01:22:09.932 - device netclient.0 01:22:09.947 - dev: Created  netclient.0 09C42BE8
OK
01:22:09.994 - Api2BaseModule.GetAdditionInfo
01:22:32.368 - 
01:22:32.368 - 
01:22:32.383 - ########################### Mark of start channel changing.###########################
01:22:32.399 - 
01:22:32.415 - GraphManager::MainChangeChannel Deluxe Music  1 provs
01:22:32.430 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
01:22:32.446 - SetChannelLogic::SetChannel 0
01:22:32.461 - SetChannelLogic::SetNormalChannel
01:22:32.477 - DevicesManager::CreateCloneDevice
01:22:32.493 - DevicesManager::CreateCloneDevice OK - founded in list
01:22:32.493 - TransponderManager
01:22:32.508 - CTransponderManager::InitOnNewDevice 0CD2083C
01:22:32.524 - SetOwner N -> Incoming Stream Cache
01:22:32.540 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
01:22:32.555 - Normal TsInputTrafficProcessor.
01:22:32.571 - FilterManager::InitOnNewDevice 1
01:22:32.586 - TS router+MarkerFinder -> InputTrafficProcessor
01:22:32.602 - Incoming Stream Cache -> TS router+MarkerFinder
01:22:32.618 - InputTrafficProcessor -> Router to timeshift
01:22:32.633 - InputTrafficProcessor(FM) -> FilterManager
01:22:32.649 - ModuleList::Free
01:22:32.664 - ChannelGraph5.CChannelGraph5
01:22:32.680 - TransponderManager::AddChildChannel
01:22:32.696 - TimeShift.TimeShift
01:22:32.711 - TimeShift.Create 400 mbytes 
01:22:32.711 - ts:One channel
01:22:32.711 - HugeMemoryBlocksManager::GetBlock 400 mbytes OK
01:22:32.727 - TransponderManager::CreateTimeshiftBuffer OK
01:22:32.758 - TimeShift.CreateReadingCursor
01:22:32.774 - TransponderChannelSource.Plugins 0ECE2FE0 csa 00000000
01:22:32.789 - TsInputTrafficProcessor.AddChannel 0
01:22:32.821 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
01:22:32.836 - ChannelGraph5.SetActiveState 1
01:22:32.836 - TransponderManager::SelectChannel
01:22:32.852 - TsInputTrafficProcessor.SelectChannel 0
01:22:32.868 - Api2BaseModule.UpdateAllOpenedChannels 0
01:22:32.883 - AV:UpdateAudioParamsAfterActive
01:22:32.899 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:32.914 - Graph::SetAudioChannelMask
01:22:32.930 - 
01:22:32.946 - TransponderManager::SetTransponder (CH) 0 Deluxe Music
01:22:32.961 - *Transport TS
01:22:32.977 - Api2Device::SetChannel http://am01.spr24.net/14029/mpegts?token=4u6VY92quYvYVm
01:22:33.086 - Api2Device::SetChannel result OK
01:22:33.086 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
01:22:33.102 - TsInputTrafficProcessor.OpenTransponder 1
01:22:33.118 - FilterManager::OnSetTransponder SetChannel
01:22:33.118 - Api2BaseModule.OnTunerStateChanged Connecting
01:22:33.118 - TimeShift.Reset
01:22:33.133 - TimeShift.Cursor reset
01:22:33.133 - ReaderCursor::Reset.. OK
01:22:33.133 - Prereader.Reset ...-OK
01:22:33.133 - Create EPGparser on SetChannel
01:22:33.133 - DvbEpgParser::SetChannel ID=3cf00002.fd913380fa23c515.abcd
01:22:33.164 - DvbEpgParser::mode normal pos=0
01:22:33.164 - ModuleList::ChangeChannel 0
01:22:33.196 - [102]ChannelGraph5::StartChannel Deluxe Music
01:22:33.211 - 
01:22:33.227 - [102]       #################### Start stream. (0.860ms) #######################
01:22:33.243 - 
01:22:33.289 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
01:22:33.289 - TimeShift.Cursor SetChannel::v 0 (0)
01:22:33.305 - ReaderCursor::SetChannel.. OK
01:22:33.336 - ReaderCursor::SetPosition 0 (w=0)
01:22:33.352 - Prereader.SetChannel..
01:22:33.368 - Prereader.SetChannel unmask 1
01:22:33.383 - Plugin::SetChannel 43981
01:22:33.383 - TransponderManager::SelectChannel
01:22:33.399 - TsInputTrafficProcessor.SelectChannel 43981
01:22:33.414 - Api2BaseModule.UpdateAllOpenedChannels 1
01:22:33.414 - Api2BaseModule.OnTunerStateChanged Preparing
01:22:33.430 - TsInputTrafficProcessor.UpdateChannel 43981
01:22:33.461 - GraphState: Undef by StartChannelIsReady
01:22:33.477 - PreScanner.StartChannelIsReady net=1 parseall=1
01:22:33.493 - VideoMediaTypeDetection: mpeg2
01:22:33.493 - SetMarkerAudioLngStream Gen New stream
01:22:33.493 - SimpleTsDemux::Init apid=-1, vpid=100
01:22:33.493 - GraphState: RequestPATScan by StartChannelIsReady TS
01:22:33.493 - Api2BaseModule.Filters_EnabledFullTS 1
01:22:33.493 - PlayTimePATParser.OpenParser 43981
01:22:33.493 - Open TSTS filter pid=0 data=0
01:22:33.493 - TSTS cur size=0
01:22:33.493 - Graph::SetVideoRect {8 8 712 568}
01:22:33.493 - Api2BaseModule.OnTunerStateChanged Playing
01:22:33.508 - [102]OnChangeCurrentGraph2 -endOfSetChannel
01:22:33.524 - ChannelGraph5.SetActiveState 1
01:22:33.602 - Graph::SetVideoWindowRect {33 0 166 100}
01:22:33.602 - Graph::UpdateRenderMode
01:22:33.690 - GraphManager::MultiChannelRendering
01:22:33.691 - ChannelGraph5.SetActiveState 1
01:22:33.720 - Graph::SetVideoWindowRect {249 0 1351 827}
01:22:33.988 - OK. 102
01:22:34.355 - Api2BaseModule.OnTunerStateChanged Playing
01:22:34.375 - Api2BaseModule.OnTunerStateChanged Connecting
01:22:34.488 - * s:0 q:0 tr=0 (0) Connecting
01:22:34.626 - Api2BaseModule.OnTunerStateChanged Preparing
01:22:34.720 - Api2BaseModule.OnTunerStateChanged Playing
01:22:34.817 - 
01:22:34.818 -        #################### First traffic. (2.453ms) #######################
01:22:34.839 - 
01:22:34.856 - TransportMarker: TS
01:22:34.872 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
01:22:34.888 - --SwitchEngineTransport TS > TS
01:22:34.904 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
01:22:34.920 - PreScanner.OnTransportMarker TS
01:22:34.937 - --SwitchEngineTransport end
01:22:35.394 - * s:100 q:100 tr=376 (0) Preparing
01:22:35.652 - OSDManager::Window_Add
01:22:35.669 - OSDManager::Window_Show
01:22:35.775 - OSDManager::Window_Show
01:22:35.809 - OSDManager::Window_Show
01:22:36.415 - * s:100 q:100 tr=188 (0) Preparing
01:22:37.418 - * s:100 q:100 tr=188 (0) Preparing
01:22:38.426 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:22:39.433 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:22:40.425 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:22:41.432 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:22:42.425 - * s:100 q:100 tr=0 (0) PreparingButTooLong
01:22:43.229 - PlayTimePATParser.EndTask
01:22:43.229 - PreScanner.OnAfterScanPat count=2 {1;4095}
01:22:43.246 - Api2BaseModule.Filters_EnabledFullTS 0
01:22:43.263 - GraphState: RequestPMTScan by OnAfterScanPat
01:22:43.279 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
01:22:43.295 - Open TSTS filter pid=4095 data=2
01:22:43.312 - TSTS cur size=15792
01:22:43.329 - TSTS quick finish
01:22:43.345 - PreScanner.OnAfterScanPmt pmt=4095
01:22:43.362 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
01:22:43.379 - PlayTimePmtParser::StopParser
01:22:43.574 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
01:22:43.575 - TimeShift.Cursor SetChannel::v 398372 (398372)
01:22:43.592 - Prereader.SetChannel unmask 1
01:22:43.610 - Plugin::SetChannel 1
01:22:43.626 - TransponderManager::SelectChannel
01:22:43.642 - TsInputTrafficProcessor.SelectChannel 1
01:22:43.663 - Api2BaseModule.UpdateAllOpenedChannels 1
01:22:43.679 - TsInputTrafficProcessor.UpdateChannel 1
01:22:43.696 - PreScanner.StartChannelIsReady net=1 parseall=0
01:22:43.712 - LngDefaultSelector.try select audio pid=221 type=1 lng=7693668-deu(goh,goh,rus) l=99
01:22:43.729 - ChannelGraph5::AfterDetectChannelParams
01:22:43.746 - VideoMediaTypeDetection: h.264
01:22:43.763 - SimpleTsDemux::Init apid=221, vpid=211
01:22:43.780 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
01:22:43.797 - demux: ProgDemux
01:22:43.821 - CalcPositionMinusDeltaTime  0 000000 old 8s new 6s delta 2s result 0 001128
01:22:43.835 - TimeShift.Cursor PushMode 1 1 (pos=1) [StartChannelHelperBase(sl)]
01:22:43.859 - Prereader::ReaderCursor.. OK
01:22:43.894 - ReaderCursor::SetPosition 1128 (w=10759322573209600)
01:22:43.912 - Prereader.StartPlayback Push buffering=0 recv=0EDA7D20 [StartChannelHelperBase(sl)]
01:22:43.929 - Prereader.StartPlayback after lock
01:22:43.947 - PreScanner.StartPmtParserIfNeed
01:22:43.947 - VideoParser::First packet 83625 h.264
01:22:43.981 - h264 detected 0 ms
01:22:43.998 - AudioParser::First packet 1536
01:22:44.015 - AudioParser::packet 1536
01:22:44.031 - AudioParser::AC3 detected
01:22:44.048 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
01:22:43.964 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
01:22:44.081 - Open TSTS filter pid=4095 data=2
01:22:44.097 - TSTS cur size=3232472
01:22:44.064 - PreScanner.Post_InitRender [ReceiveTraffic]
01:22:44.148 - * s:100 q:100 tr=3458636 (0) Preparing
01:22:44.264 - [102]sm: TvInitRenderer 1
01:22:44.265 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
01:22:44.283 - CalculBufferingSize prov=0CD21C7C marker=1 request=1
01:22:44.302 - CalculBuffering 2000ms br=2000kb to 500 kbytes. By type
01:22:44.319 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
01:22:44.340 - Prereader::ReaderCursor.. OK
01:22:44.375 - ReaderCursor::SetPosition 0 (w=18384916748173312)
01:22:44.392 - Prereader.StartPlayback Pull buffering=512000 recv=00000000 [EnablePullModeAndStartPlayback]
01:22:44.410 - Prereader.StartPlayback after lock
01:22:44.427 - Buffering/prereader size 500/3906 kbytes
01:22:44.463 - ------sGetVideoParams
01:22:44.463 - ChannelGraph5.CreateInterfacesAndRenderGraph
01:22:44.493 - subsm.StartChannel demux=ProgDemux tr=TS
01:22:44.500 - subsm.RecreateSubtitlesParsers 12522560 ts=1 subindex=-1
01:22:44.519 - subsm.Set Teletext parser 0
01:22:44.538 - subsm.RecreateSubtitlesParsers end
01:22:44.554 - subs: OnSetChannel
01:22:44.572 - CreateCustomGraph
01:22:44.590 - VideoType:H.264/AVC ffmpeg=0
01:22:44.608 - Video/4.H264 : MPEG2_VIDEO s={0,200,0,0}, t={0,200,0,0}, bmp=1280x1088, r=254/143, br=0,frametime=40ms, IF=0x0, CF=0x0)
01:22:44.626 - Add video codec ee30215d-164f-4a92-a4eb-9d4c13390f9f
01:22:44.706 - OK
01:22:44.706 - CChannelGraph5::CreateRender 10
01:22:44.727 - VR: EVR Custom
01:22:44.747 - VR: create new render
01:22:44.834 - VR: Add video renderer Video Renderer - OK
01:22:44.834 - CreateAudioPartOfGraph
01:22:44.857 - AudioType:AC3 
01:22:44.875 - Audio/AC3 : WaveFormatEx (2ch 48000 0bit formatTag= 0x0)
01:22:44.894 - Add audio codec 4a2286e0-7bef-11ce-9bd9-0000e202599c - Pin for connection not found! 0x80040207
01:22:44.931 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Panasonic-TV (Аудио Intel(R) для дисплеев) OK (AC3)
01:22:44.931 - Added audio renderer
01:22:45.031 - Try to found any codec before renderer... OK.
01:22:45.054 - Graph::AfterRender
01:22:45.072 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
01:22:45.091 - AV:UpdateAudioParamsAfterActive
01:22:45.109 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:22:45.126 - Graph::SetVolume 100% 0db
01:22:45.148 - Graph::SetAudioChannelMask
01:22:45.163 - Graph::Run
01:22:45.286 - Graph::Run 1
01:22:45.286 - Graph::SetPictureParams
01:22:45.303 - Graph::SetVideoRect {16 16 1264 1068}
01:22:45.321 - Graph::SetVideoWindowRect {66 0 1534 827}
01:22:45.363 - 
01:22:45.363 - [0]########################### Mark of finish channel changing. (13.000ms) #############
01:22:45.447 - 
01:22:45.818 - 
01:22:45.818 - [0]########################### First video frame. (13.453ms) #############
01:22:45.874 - 
01:22:45.883 - CChannelGraph5::HideSubtitles
01:22:45.975 - * s:100 q:100 tr=4634576 (0) Playing
01:22:47.642 - * s:100 q:100 tr=365096 (0) Playing
01:22:48.260 - * s:100 q:100 tr=0 (0) Playing
01:22:48.774 - * s:0 q:100 tr=188 (0) Playing
01:22:49.451 - * s:57 q:100 tr=300800 (0) Playing
01:22:50.044 - * s:100 q:100 tr=231804 (0) Playing
01:22:50.578 - * s:0 q:100 tr=1504 (0) Playing
01:22:51.470 - OSDManager::Window_Show
01:22:51.471 - OSDManager::Window_Delete
01:22:51.559 - * s:48 q:100 tr=250040 (0) Playing
01:22:52.928 - * s:0 q:100 tr=413412 (0) Playing
01:22:52.930 - * s:0 q:100 tr=0 (0) Playing
01:22:53.613 - * s:0 q:100 tr=1479748 (0) Playing
01:22:54.216 - * s:0 q:100 tr=721168 (0) Playing
01:22:55.448 - * s:100 q:100 tr=2291156 (0) Playing
01:22:56.445 - * s:100 q:100 tr=236692 (0) Playing
01:22:57.461 - * s:100 q:100 tr=260756 (0) Playing
01:22:58.448 - * s:100 q:100 tr=302116 (0) Playing
01:22:59.463 - * s:100 q:100 tr=261508 (0) Playing
01:23:00.315 - * s:0 q:100 tr=256620 (0) Playing
01:23:01.109 - * s:0 q:100 tr=0 (0) Playing
01:23:02.468 - * s:52 q:100 tr=271096 (0) Playing
01:23:03.483 - * s:94 q:100 tr=220148 (0) Playing
01:23:04.089 - * s:100 q:100 tr=560052 (0) Playing
01:23:04.671 - * s:100 q:100 tr=556292 (0) Playing
01:23:05.209 - * s:0 q:100 tr=930036 (0) Playing
01:23:05.797 - * s:100 q:100 tr=915372 (0) Playing
01:23:06.548 - * s:100 q:100 tr=1324836 (0) Playing
01:23:07.475 - * s:100 q:100 tr=90240 (0) Playing
01:23:08.494 - * s:100 q:100 tr=11092 (0) Playing
01:23:09.936 - * s:0 q:100 tr=453456 (0) Playing
01:23:10.549 - * s:16 q:100 tr=84036 (0) Playing
01:23:11.506 - * s:66 q:100 tr=260756 (0) Playing
01:23:12.516 - * s:27 q:100 tr=346296 (0) Playing
01:23:13.190 - * s:100 q:100 tr=953724 (0) Playing
01:23:14.518 - * s:100 q:100 tr=1851236 (0) Playing
01:23:15.525 - * s:100 q:100 tr=144572 (0) Playing
01:23:16.515 - * s:100 q:100 tr=298356 (0) Playing
01:23:17.512 - * s:100 q:100 tr=145512 (0) Playing
01:23:18.515 - * s:51 q:100 tr=265080 (0) Playing
01:23:19.139 - * s:100 q:100 tr=262636 (0) Playing
01:23:20.561 - * s:55 q:100 tr=289332 (0) Playing
01:23:21.193 - * s:100 q:100 tr=245340 (0) Playing
01:23:22.574 - * s:51 q:100 tr=264328 (0) Playing
01:23:23.162 - * s:100 q:100 tr=253988 (0) Playing
01:23:23.792 - * s:0 q:100 tr=2315972 (0) Playing
01:23:24.581 - * s:100 q:100 tr=2798568 (0) Playing
01:23:25.579 - * s:100 q:100 tr=457404 (0) Playing
01:23:26.575 - * s:100 q:100 tr=273164 (0) Playing
01:23:27.575 - * s:100 q:100 tr=204732 (0) Playing
01:23:28.603 - * s:100 q:100 tr=312080 (0) Playing
01:23:29.448 - * s:0 q:100 tr=286512 (0) Playing
01:23:30.600 - * s:46 q:100 tr=241580 (0) Playing
01:23:31.234 - * s:46 q:100 tr=0 (0) Playing
01:23:32.330 - * s:100 q:100 tr=311328 (0) Playing
01:23:33.618 - * s:100 q:100 tr=3375164 (0) Playing
01:23:34.619 - * s:100 q:100 tr=99828 (0) Playing
01:23:35.617 - * s:100 q:100 tr=224472 (0) Playing
01:23:36.626 - * s:100 q:100 tr=164876 (0) Playing
01:23:37.638 - * s:100 q:100 tr=10340 (0) Playing
01:23:38.696 - * s:100 q:100 tr=277488 (0) Playing
01:23:39.339 - * s:0 q:100 tr=296288 (0) Playing
01:23:40.360 - ChannelGraph5.~CChannelGraph5
01:23:40.361 - Graph::Stop
01:23:40.408 - Before Stop ...01:23:40.426 - OnGraphStop ...01:23:40.457 - PreScanner.Stop
01:23:40.477 - GraphState: Undef by PreScanner.Stop
01:23:40.498 - PlayTimePmtParser::StopParser
01:23:40.517 - subsm.StopChannel
01:23:40.536 - VR: Disconnect
01:23:40.614 - Graph::ReleaseAllFilters
01:23:40.632 - release: Prog Audio renderer - RR - OK.
01:23:40.750 - release: Video Renderer - RR - OK.
01:23:40.803 - release: Video decoder  - RR - OK.
01:23:40.877 - release: LAV Audio Decoder - RR - OK.
01:23:40.932 - FilterGraph: 1
01:23:40.975 - ChannelGraph5.~CChannelGraph5 finish
01:23:40.991 - TransponderManager::CloseChannel
01:23:40.991 - TsInputTrafficProcessor.RemoveChannel 1
01:23:40.991 - ~TransponderChannelSource
01:23:41.163 - TimeShift.DestroyReadingCursor
01:23:41.163 - Api2BaseModule.UpdateAllOpenedChannels 0
01:23:41.178 - Destroy TSTS filter pid=0
01:23:41.194 - PlayTimePmtParser::StopParser
01:23:41.225 - Destroy TSTS filter pid=4095
01:23:41.241 - DevicesManager::DestroyTransponderManager
01:23:41.257 - Api2Device::SetChannel - None
01:23:41.382 - Api2Device::SetChannel result OK
01:23:41.382 - SetOwner N -> 0
01:23:41.397 - SetOwner SetTransponder null
01:23:41.413 - ~TransponderManager
01:23:41.428 - SetOwner N -> 0
01:23:41.444 - SetOwner SetTransponder null
01:23:41.475 - TimeShift.~TimeShift
01:23:41.475 - HugeMemoryBlocksManager::Free
01:23:41.491 - ModuleList::Free
01:23:41.522 - [103]OnChangeCurrentGraph2 -CloseGraph
01:23:41.600 - VR: GUI
01:23:41.616 - GraphManager::MultiChannelRendering
01:23:42.741 - 
01:23:42.741 - Stop engine
01:23:42.757 - DevicesManager::Stop
01:23:42.772 - DevicesManager::Free devices
01:23:42.788 - SDP:Destroy netclient.0 own=00000000
01:23:42.850 - SDP:after destroy device
01:23:42.850 - DevicesManager::Free RC
01:23:42.866 - Engine stopped
01:23:42.928 - 
01:23:42.928 - Stop engine
01:23:42.944 - DevicesManager::Stop
01:23:42.960 - DevicesManager::Free devices
01:23:42.975 - Engine stopped
01:23:43.194 - HugeMemoryBlocksManager::FreeAll
