﻿23:57:01.087 - Start logging...
23:57:01.102 - 
23:57:01.102 - Start engine 7.000
23:57:01.102 - ExceptionFilter::Init. restart=1
23:57:02.149 - DevicesManager::Start
23:57:02.164 - AVManager::Init
23:57:02.289 - SetDefaultNullCodecs
23:57:02.508 - IsWin8WithoutMpeg2Codecs 0
23:57:02.508 - IsWin8WithoutMpeg2Codecs 1
23:57:02.524 - IsWin8WithoutMpeg2Codecs 2
23:57:02.555 - SetDefaultNullCodecs
23:57:02.571 - AV settings loaded
23:57:02.602 - VR: GUI
23:57:02.617 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:02.724 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:02.724 - AV:UpdateAudioParamsAfterActive
23:57:02.724 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:02.739 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:02.974 - DevicesManager::StartDevices...
23:57:02.989 - device bda.2400 23:57:09.363 - dev: Created  bda.960 0C6D9888
OK
23:57:09.394 - device netclient.0 23:57:09.410 - dev: Created  netclient.0 00A08C18
OK
23:57:09.425 - Api2BaseModule.GetAdditionInfo
23:57:09.441 - Api2BaseModule.GetAdditionInfo
23:57:10.300 - 
23:57:10.316 - 
23:57:10.316 - ########################### Mark of start channel changing.###########################
23:57:10.331 - 
23:57:10.331 - GraphManager::MainChangeChannel arte  1 provs
23:57:10.347 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:57:10.347 - SetChannelLogic::SetChannel 0
23:57:10.362 - SetChannelLogic::SetNormalChannel
23:57:10.362 - TransponderManager
23:57:10.362 - CTransponderManager::InitOnNewDevice 0D986DCC
23:57:10.378 - SetOwner { -> Incoming Stream Cache
23:57:10.378 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
23:57:10.394 - Normal TsInputTrafficProcessor.
23:57:10.409 - FilterManager::InitOnNewDevice 1
23:57:10.409 - TS router+MarkerFinder -> InputTrafficProcessor
23:57:10.425 - Incoming Stream Cache -> InputTrafficProcessor
23:57:10.425 - InputTrafficProcessor -> Router to timeshift
23:57:10.441 - InputTrafficProcessor(FM) -> FilterManager
23:57:10.441 - ModuleList::Free
23:57:10.456 - Api2BaseModule::GetCurrentDeviceInfo
23:57:10.456 - ModuleList::Load C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module OK
23:57:10.472 - Api2BaseModule::GetCurrentDeviceInfo
23:57:10.487 - ModuleList::Load C:\Program Files (x86)\ProgDVB Professional\Modules\ProgMediaServer.Module OK
23:57:10.503 - ChannelGraph5.CChannelGraph5
23:57:10.503 - TransponderManager::AddChildChannel
23:57:10.519 - TimeShift.TimeShift
23:57:10.519 - TimeShift.Create 200 mbytes 
23:57:10.534 - ts:One channel
23:57:10.534 - HugeMemoryBlocksManager::GetBlock 200 mbytes OK
23:57:10.550 - TransponderManager::CreateTimeshiftBuffer OK
23:57:10.597 - TimeShift.CreateReadingCursor
23:57:10.612 - TsInputTrafficProcessor.AddChannel 0
23:57:10.612 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
23:57:10.628 - ChannelGraph5.SetActiveState 1
23:57:10.628 - TransponderManager::SelectChannel
23:57:10.644 - TsInputTrafficProcessor.SelectChannel 0
23:57:10.644 - Api2BaseModule.UpdateAllOpenedChannels 0
23:57:10.691 - AV:UpdateAudioParamsAfterActive
23:57:10.691 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:10.706 - Graph::SetAudioChannelMask
23:57:10.722 - 
23:57:10.722 - TransponderManager::SetTransponder (CH) 10744000 arte
23:57:10.737 - *Transport TS
23:57:10.737 - Api2Device::SetChannel 10744000Khz sid=28724
23:57:10.753 - ch:arte {freq:10744, pol:H, sr:22000}
23:57:16.002 - Api2Device::SetChannel result OK
23:57:16.017 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=28724
23:57:16.017 - TsInputTrafficProcessor.OpenTransponder 1
23:57:16.033 - FilterManager::OnSetTransponder SetChannel
23:57:16.033 - TimeShift.Reset
23:57:16.049 - TimeShift.Cursor reset
23:57:16.049 - ReaderCursor::Reset.. OK
23:57:16.080 - Prereader.Reset ...-OK
23:57:16.095 - Create EPGparser on SetChannel
23:57:16.111 - DvbEpgParser::SetChannel ID=80010960.c0000000a3f0c0.7034
23:57:16.127 - ModuleList::ChangeChannel 10744000
23:57:16.142 - CChannelGraph5::StartChannel arte
23:57:16.142 - 
23:57:16.158 -        #################### Start stream. (5.844ms) #######################
23:57:16.158 - 
23:57:16.283 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
23:57:16.283 - TimeShift.Cursor SetChannel::v 0 (0)
23:57:16.299 - ReaderCursor::SetChannel.. OK
23:57:16.314 - ReaderCursor::SetPosition 0 (w=0)
23:57:16.330 - Prereader.SetChannel..
23:57:16.330 - Prereader.SetChannel unmask 1
23:57:16.345 - Plugin::SetChannel 28724
23:57:16.361 - TransponderManager::SelectChannel
23:57:16.361 - TsInputTrafficProcessor.SelectChannel 28724
23:57:16.377 - Api2BaseModule.UpdateAllOpenedChannels 1
23:57:16.408 - TsInputTrafficProcessor.UpdateChannel 28724
23:57:16.408 - GraphState: Undef by StartChannelIsReady
23:57:16.424 - PreScanner.StartChannelIsReady net=0 parseall=0
23:57:16.424 - LngDefaultSelector.try select audio pid=402 type=1 lng=7693668-deu(goh,goh,rus) l=99
23:57:16.424 - 
23:57:16.439 -        #################### First traffic. (6.125ms) #######################
23:57:16.455 - 
23:57:16.439 - LngDefaultSelector.try select audio pid=403 type=1 lng=7563629-mis(goh,goh,rus) l=99
23:57:16.470 - LngDefaultSelector.try select audio pid=407 type=1 lng=7107949-mul(goh,goh,rus) l=99
23:57:16.470 - LngDefaultSelector.try select audio pid=408 type=1 lng=6386278-fra(goh,goh,rus) l=99
23:57:16.486 - ChannelGraph5::AfterDetectChannelParams
23:57:16.502 - VideoMediaTypeDetection: mpeg2
23:57:16.517 - SetMarkerAudioLngStream Gen New stream
23:57:16.517 - SimpleTsDemux::Init apid=402, vpid=401
23:57:16.533 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:57:16.533 - demux: ProgDemux
23:57:16.560 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
23:57:16.569 - Prereader::ReaderCursor.. OK
23:57:16.586 - ReaderCursor::SetPosition 0 (w=657267435241472)
23:57:16.594 - Prereader.StartPlayback Push buffering=0 recv=16D43B90 [StartChannelHelperBase(sl)]
23:57:16.604 - Prereader.StartPlayback after lock
23:57:16.616 - PreScanner.StartPmtParserIfNeed
23:57:16.616 - VideoParser::First packet 34128 mpeg2
23:57:16.625 - PlayTimePmtParser::OpenParser SID=28724 PMT=400 unknow channel=0
23:57:16.648 - Open TSTS filter pid=400 data=2
23:57:16.637 - VideoParser::packet 66248
23:57:16.661 - TSTS cur size=239700
23:57:16.672 - VideoParser::packet 99976
23:57:16.683 - Graph::SetVideoRect {8 8 712 568}
23:57:16.706 - ChannelGraph5.SetActiveState 1
23:57:16.695 - AudioParser::First packet 2304
23:57:16.723 - AudioParser::MPG12 detected
23:57:16.732 - VideoParser::packet 116064
23:57:16.733 - Graph::SetVideoWindowRect {33 0 166 100}
23:57:16.740 - VideoParser::packet 153008
23:57:16.749 - Graph::UpdateRenderMode
23:57:16.758 - VideoParser::packet 184688
23:57:16.779 - VideoParser::packet 207104
23:57:16.790 - VideoParser::packet 241192
23:57:16.802 - VideoParser::packet 274160
23:57:16.817 - VideoParser::packet 297696
23:57:16.861 - VideoParser::packet 334880
23:57:16.931 - VideoParser::packet 392872
23:57:16.932 - GraphManager::MultiChannelRendering
23:57:16.950 - VideoParser::mpeg2::Sequence header detected. len140
23:57:16.961 - ChannelGraph5.SetActiveState 1
23:57:16.973 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:57:16.992 - PreScanner.Post_InitRender [ReceiveTraffic]
23:57:16.995 - Graph::SetVideoWindowRect {0 7 703 534}
23:57:17.044 - OK. 102
23:57:17.104 - OSDManager::Window_Add
23:57:17.113 - OSDManager::Window_Show
23:57:17.247 - * s:98 q:96 tr=5099312 (0) 
23:57:17.290 - OSDManager::Window_Show
23:57:17.302 - OSDManager::Window_Show
23:57:17.443 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
23:57:17.452 - CalculBufferingSize prov=0D98820C marker=0 request=1
23:57:17.461 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:57:17.474 - Prereader::ReaderCursor.. OK
23:57:17.501 - ReaderCursor::SetPosition 0 (w=4687269608816640)
23:57:17.513 - Prereader.StartPlayback Pull buffering=0 recv=00000000 [EnablePullModeAndStartPlayback]
23:57:17.525 - Prereader.StartPlayback after lock
23:57:17.537 - ------sGetVideoParams
23:57:17.549 - ChannelGraph5.CreateInterfacesAndRenderGraph
23:57:17.561 - subsm.StartChannel demux=ProgDemux tr=TS
23:57:17.574 - subsm.RecreateSubtitlesParsers 0D9A5850 ts=1 subindex=-1
23:57:17.587 - SSTeletext: SetChannel page=0 lng
23:57:17.599 - subsm.Set Teletext parser 404
23:57:17.612 - subsm.RecreateSubtitlesParsers end
23:57:17.623 - subs: OnSetChannel
23:57:17.635 - CreateCustomGraph
23:57:17.648 - VideoType:MPEG2
23:57:17.660 - Video/MPEG2_VIDEO : MPEG2_VIDEO s={0,720,720,0}, t={0,720,720,0}, bmp=720x576, r=16/9, br=15305200,frametime=40ms, IF=0x0, CF=0x0)
23:57:17.724 - Add video codec 04fe9017-f873-410e-871e-ab91661a4ef7 - OK
23:57:17.737 - CChannelGraph5::CreateRender 10
23:57:17.750 - VR: EVR Custom
23:57:17.762 - VR: create new render
23:57:17.838 - VR: Add video renderer Video Renderer - OK
23:57:17.856 - CreateAudioPartOfGraph
23:57:17.869 - AudioType:MPG
23:57:17.878 - Audio/MPEG1AudioPayload : WaveFormatEx (2ch 48000 0bit formatTag= 0x50)
23:57:17.888 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
23:57:17.934 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Primary Sound Driver OK (normal)
23:57:17.943 - AP: created
23:57:17.952 - Added audio processor
23:57:17.967 - Connect codec to AP - OK
23:57:17.989 - Add and connect audio renderer - OK
23:57:18.002 - Graph::AfterRender
23:57:18.015 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
23:57:18.028 - AV:UpdateAudioParamsAfterActive
23:57:18.041 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:57:18.054 - Graph::SetVolume 100% 0db
23:57:18.070 - Graph::SetAudioChannelMask
23:57:18.084 - AudioProcessor::SetAudioChannelsMask
23:57:18.097 - AudioProcessor::Equalizer_SetBands
23:57:18.111 - Graph::Run
23:57:18.130 - Graph::Run 1
23:57:18.144 - Graph::SetPictureParams
23:57:18.157 - Graph::SetVideoRect {8 8 712 568}
23:57:18.171 - Graph::SetVideoWindowRect {0 73 703 468}
23:57:18.185 - AfterRenderInit
23:57:18.184 - 
23:57:18.213 - ########################### Mark of finish channel changing. (7.907ms) #############
23:57:18.227 - 
23:57:18.199 - ARI
23:57:18.253 - CChannelGraph5::HideSubtitles
23:57:18.391 - 
23:57:18.401 - ########################### First video frame. (8.094ms) #############
23:57:18.414 - 
23:57:18.464 - * s:98 q:96 tr=5132400 (0) 
23:57:20.176 - * s:98 q:96 tr=7238000 (0) 
23:57:21.543 - * s:98 q:96 tr=5790400 (0) 
23:57:22.447 - OSDManager::Window_Show
23:57:22.473 - OSDManager::Window_Delete
23:57:23.540 - * s:98 q:96 tr=8422400 (0) 
23:57:25.547 - * s:98 q:96 tr=8488200 (0) 
23:57:27.541 - * s:98 q:96 tr=8422400 (0) 
23:57:29.546 - * s:98 q:96 tr=8455300 (0) 
23:57:31.714 - Graph::SetPictureParams
23:57:31.743 - Graph::SetPictureParams
23:57:31.757 - Graph::SetPictureParams
23:57:31.766 - Graph::SetPictureParams
23:57:31.939 - * s:98 q:96 tr=10100300 (0) 
23:57:33.541 - * s:98 q:96 tr=6777400 (0) 
23:57:35.543 - * s:98 q:96 tr=8455300 (0) 
23:57:37.551 - * s:98 q:96 tr=8488200 (0) 
23:57:39.548 - * s:98 q:96 tr=8422400 (0) 
23:57:41.550 - * s:98 q:96 tr=8455300 (0) 
23:57:43.548 - * s:98 q:96 tr=8455300 (0) 
23:57:45.546 - * s:98 q:96 tr=8422400 (0) 
23:57:47.539 - * s:98 q:96 tr=8422400 (0) 
23:57:49.546 - * s:98 q:96 tr=8488200 (0) 
23:57:51.531 - * s:98 q:96 tr=8389500 (0) 
23:57:53.530 - * s:98 q:96 tr=8422400 (0) 
23:57:55.542 - * s:98 q:95 tr=8521100 (0) 
23:57:57.542 - * s:98 q:96 tr=8422400 (0) 
23:57:59.547 - * s:98 q:96 tr=8488200 (0) 
23:58:01.546 - * s:98 q:95 tr=8422400 (0) 
23:58:03.542 - * s:98 q:96 tr=8455300 (0) 
23:58:05.544 - * s:98 q:96 tr=8455300 (0) 
23:58:07.544 - * s:98 q:96 tr=8422400 (0) 
23:58:09.547 - * s:98 q:96 tr=8488200 (0) 
23:58:11.551 - * s:98 q:96 tr=8455300 (0) 
23:58:13.623 - DevicesManager::EnableCISupport
23:58:13.664 - Api2Device::CI3_EnableCISupport
23:58:13.696 - Api2Device::CI3_EnableCISupport
23:58:13.707 - CAVManager::SetSettings
23:58:14.567 - * s:98 q:96 tr=12732300 (0) 
23:58:16.546 - * s:98 q:96 tr=8356600 (0) 
23:58:18.545 - * s:98 q:96 tr=8455300 (0) 
23:58:20.547 - * s:98 q:96 tr=8455300 (0) 
23:58:22.547 - * s:98 q:96 tr=8455300 (0) 
23:58:24.533 - * s:98 q:96 tr=8389500 (0) 
23:58:26.546 - * s:98 q:96 tr=8488200 (0) 
23:58:28.544 - * s:98 q:96 tr=8455300 (0) 
23:58:30.548 - * s:98 q:96 tr=8455300 (0) 
23:58:32.543 - * s:98 q:96 tr=8422400 (0) 
23:58:34.543 - * s:98 q:96 tr=8455300 (0) 
23:58:36.530 - * s:98 q:96 tr=8389500 (0) 
23:58:38.534 - * s:98 q:96 tr=8455300 (0) 
23:58:40.545 - * s:98 q:96 tr=8488200 (0) 
23:58:42.545 - * s:98 q:96 tr=8455300 (0) 
23:58:44.546 - * s:98 q:96 tr=8455300 (0) 
23:58:46.546 - * s:98 q:96 tr=8455300 (0) 
23:58:48.541 - * s:98 q:95 tr=8422400 (0) 
