﻿09:07:04.866 - Start logging...
09:07:04.876 - 
09:07:04.876 - Start engine 6.210
09:07:04.876 - ExceptionFilter::Init. restart=1
09:07:07.101 - DevicesManager::Start
09:07:07.101 - CAVManager::Init
09:07:07.431 - AV settings loaded
09:07:07.431 - VR: GUI
09:07:07.451 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:07.531 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:07.531 - AV:UpdateAudioParamsAfterActive
09:07:07.531 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:07.531 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:07.841 - DevicesManager::StartDevices...
09:07:07.841 - device bda.52394 09:07:08.115 - dev: Created  bda.ccaa 00F96530
OK
09:07:08.117 - device bda.52393 09:07:08.206 - dev: Created  bda.cca9 00F95840
OK
09:07:08.208 - device netclient.0 09:07:08.224 - dev: Created  netclient.0 05FBDAF0
OK
09:07:08.225 - Api2BaseModule.GetAdditionInfo
09:07:08.226 - Api2BaseModule.GetAdditionInfo
09:07:08.227 - Api2BaseModule.GetAdditionInfo
09:07:08.964 - 
09:07:08.964 - 
09:07:08.965 - ########################### Mark of start channel changing.###########################
09:07:08.966 - 
09:07:08.966 - GraphManager::MainChangeChannel BBC NEWS HD  1 provs
09:07:08.967 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
09:07:08.968 - SetChannelLogic::SetChannel 0
09:07:08.968 - SetChannelLogic::SetNormalChannel
09:07:08.969 - TransponderManager
09:07:08.969 - CTransponderManager::InitOnNewDevice 0FB66664
09:07:08.970 - { -> Incoming Stream Cache
09:07:08.971 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
09:07:08.972 - Normal TsInputTrafficProcessor.
09:07:08.976 - FilterManager::InitOnNewDevice 1
09:07:08.977 - TS router+MarkerFinder -> InputTrafficProcessor
09:07:08.978 - Incoming Stream Cache -> InputTrafficProcessor
09:07:08.978 - InputTrafficProcessor -> Router to timeshift
09:07:08.979 - InputTrafficProcessor(FM) -> FilterManager
09:07:08.980 - ModuleList::Free
09:07:08.982 - Api2BaseModule::GetCurrentDeviceInfo
09:07:08.983 - ModuleList::Load C:\Program Files (x86)\ProgDVB\Modules\PidRecorder.Module OK
09:07:08.984 - Api2BaseModule::GetCurrentDeviceInfo
09:07:08.989 - ModuleList::Load C:\Program Files (x86)\ProgDVB\Modules\ProgMediaServer.Module OK
09:07:08.989 - ChannelGraph5.CChannelGraph5
09:07:08.990 - TransponderManager::AddChildChannel
09:07:08.991 - TimeShift.TimeShift
09:07:08.991 - TimeShift.Create 200 mbytes 
09:07:08.992 - ts:One channel
09:07:08.993 - HugeMemoryBlocksManager::GetBlock 200 mbytes OK
09:07:08.994 - TransponderManager::CreateTimeshiftBuffer OK
09:07:09.130 - MDPlugin::Loaded C:\Program Files (x86)\ProgDVB\Plugins\ACamd.dll OK
09:07:10.519 - TimeShift.CreateReadingCursor
09:07:10.520 - TsInputTrafficProcessor.AddChannel 0
09:07:10.525 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
09:07:10.526 - ChannelGraph5.SetActiveState 1
09:07:10.526 - TransponderManager::SelectChannel
09:07:10.527 - TsInputTrafficProcessor.SelectChannel 0
09:07:10.528 - Api2BaseModule.UpdateAllOpenedChannels 0
09:07:10.533 - AV:UpdateAudioParamsAfterActive
09:07:10.534 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:10.535 - Graph::SetAudioChannelMask
09:07:10.536 - 
09:07:10.536 - TransponderManager::SetTransponder (CH) 11023000 BBC NEWS HD
09:07:10.537 - *Transport TS
09:07:10.538 - Api2Device::SetChannel 11023000Khz sid=8921
09:07:10.539 - ch:BBC NEWS HD {freq:11023, pol:H, sr:23000}
09:07:12.989 - Api2Device::SetChannel result OK
09:07:12.990 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=8921
09:07:12.991 - TsInputTrafficProcessor.OpenTransponder 1
09:07:12.992 - FilterManager::OnSetTransponder SetChannel
09:07:12.992 - TimeShift.Reset
09:07:12.993 - TimeShift.Cursor reset
09:07:12.994 - ReaderCursor::Reset.. OK
09:07:12.995 - Prereader.Reset ...-OK
09:07:12.996 - Create EPGparser on SetChannel
09:07:12.997 - DvbEpgParser::SetChannel ID=8001ccaa.11a000000a83298.22d9
09:07:12.999 - ModuleList::ChangeChannel 11023000
09:07:13.000 - CChannelGraph5::StartChannel BBC NEWS HD
09:07:13.001 - 
09:07:13.001 -        #################### Start stream. (4.047ms) #######################
09:07:13.002 - 
09:07:13.043 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
09:07:13.043 - TimeShift.Cursor SetChannel::v 0 (0)
09:07:13.044 - ReaderCursor::SetChannel.. OK
09:07:13.045 - ReaderCursor::SetPosition 0 (w=0)
09:07:13.045 - Prereader.SetChannel..
09:07:13.046 - Prereader.SetChannel unmask 1
09:07:13.047 - Plugin::SetChannel 8921
09:07:13.059 - TransponderManager::SelectChannel
09:07:13.060 - TsInputTrafficProcessor.SelectChannel 8921
09:07:13.061 - Api2BaseModule.UpdateAllOpenedChannels 1
09:07:13.063 - TsInputTrafficProcessor.UpdateChannel 8921
09:07:13.063 - GraphState: Undef by StartChannelIsReady
09:07:13.064 - PreScanner.StartChannelIsReady net=0 parseall=0
09:07:13.065 - LngDefaultSelector.try select audio pid=5001 type=1 lng=6778469-eng(goh,goh,rus) l=99
09:07:13.065 - ChannelGraph5::AfterDetectChannelParams
09:07:13.066 - VideoMediaTypeDetection: h.264
09:07:13.066 - SetMarkerAudioLngStream Gen New stream
09:07:13.067 - 
09:07:13.067 - SimpleTsDemux::Init apid=5001, vpid=5000
09:07:13.068 -        #################### First traffic. (4.109ms) #######################
09:07:13.069 - 
09:07:13.068 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
09:07:13.070 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
09:07:13.071 - Prereader::ReaderCursor.. OK
09:07:13.072 - ReaderCursor::SetPosition 0 (w=11304353923072)
09:07:13.073 - Prereader.StartPlayback Push buffering=0 recv=0FE19668 [StartChannelHelperBase(sl)]
09:07:13.073 - Prereader.StartPlayback after lock
09:07:13.074 - PreScanner.StartPmtParserIfNeed
09:07:13.075 - PlayTimePmtParser::OpenParser SID=8921 PMT=258 unknow channel=0
09:07:13.075 - Open TSTS filter pid=258 data=2
09:07:13.076 - TSTS cur size=2632
09:07:13.076 - Graph::SetVideoRect {8 8 712 568}
09:07:13.077 - ChannelGraph5.SetActiveState 1
09:07:13.085 - Graph::SetVideoWindowRect {33 0 166 100}
09:07:13.086 - Graph::UpdateRenderMode
09:07:13.187 - VideoParser::First packet 161 h.264
09:07:13.188 - VideoParser::packet 292
09:07:13.217 - VideoParser::packet 4651
09:07:13.233 - AudioParser::First packet 1816
09:07:13.233 - AudioParser::AC3 detected
09:07:13.351 - GraphManager::MultiChannelRendering
09:07:13.352 - ChannelGraph5.SetActiveState 1
09:07:13.360 - Graph::SetVideoWindowRect {0 303 443 635}
09:07:13.365 - OK. 102
09:07:13.390 - Api2BaseModule.OpenFilter 258 OK
09:07:13.939 - VideoParser::packet 68035
09:07:13.940 - h264 detected 750 ms
09:07:13.940 - Api2BaseModule.CloseFilter 1 pid 258
09:07:13.941 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
09:07:13.942 - PreScanner.Post_InitRender [ReceiveTraffic]
09:07:13.943 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
09:07:13.944 - CalculBufferingSize prov=0FB67AA4 marker=0 request=1
09:07:13.944 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
09:07:13.945 - Prereader::ReaderCursor.. OK
09:07:13.946 - ReaderCursor::SetPosition 0 (w=576522050076672)
09:07:13.947 - Prereader.StartPlayback Pull buffering=0 recv=00000000 [EnablePullModeAndStartPlayback]
09:07:13.948 - Prereader.StartPlayback after lock
09:07:13.948 - ------sGetVideoParams
09:07:13.949 - ChannelGraph5.CreateInterfacesAndRenderGraph
09:07:13.968 - demux: MS 1
09:07:13.991 - subsm.StartChannel demux=MsDemux tr=TS
09:07:13.992 - subsm.RecreateSubtitlesParsers 05FAC7C0 ts=1 subindex=0
09:07:13.993 - SSTeletext: SetChannel page=0 lng
09:07:13.994 - subsm.Set Teletext parser 5003
09:07:13.995 - subsm.Set Subtitle parser 5004
09:07:13.996 - SSBitmap: SetChannel
09:07:13.997 - subsm.RecreateSubtitlesParsers end
09:07:13.997 - subs: OnSetChannel
09:07:13.998 - CreateCustomGraph
09:07:13.999 - VideoType:H.264/AVC
09:07:14.000 - 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)
09:07:14.046 - Add video codec 212690fb-83e5-4526-8fd7-74478b7939cd - OK
09:07:14.047 - CChannelGraph5::CreateRender 10
09:07:14.048 - VR: EVR Custom
09:07:14.049 - VR: create new render
09:07:14.106 - Graph::SetVideoWindowRect {128 0 1152 768}
09:07:14.249 - VR: Add video renderer Video Renderer - OK
09:07:14.249 - CreateAudioPartOfGraph
09:07:14.250 - AudioType:AC3
09:07:14.251 - Audio/AC3 : WaveFormatEx (2ch 48000 0bit formatTag= 0x0)
09:07:14.275 - Add audio codec a753a1ec-973e-4718-af8e-a3f554d45c44 - OK
09:07:14.345 - IMyGraphBuilder::CreateAudioRenderFilter::Default DirectSound Device OK
09:07:14.537 - Add and connect audio renderer - OK
09:07:14.538 - Graph::AfterRender
09:07:14.538 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
09:07:14.539 - AV:UpdateAudioParamsAfterActive
09:07:14.540 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
09:07:14.540 - Graph::SetVolume 0% -10000db
09:07:14.548 - Graph::SetAudioChannelMask
09:07:14.549 - Graph::Run
09:07:14.750 - Graph::Run 1
09:07:14.751 - Graph::SetPictureParams
09:07:14.752 - Graph::SetVideoRect {24 16 1896 1076}
09:07:14.753 - Graph::SetVideoWindowRect {0 21 1280 746}
09:07:14.753 - AfterRenderInit
09:07:14.754 - ARI
09:07:14.755 - CChannelGraph5::HideSubtitles
09:07:14.774 - * s:55 q:81 tr=10684228 (10904) 
09:07:15.516 - changed ratio to 1920/1080
09:07:15.517 - Graph::SetVideoRect {24 16 1896 1076}
09:07:15.517 - Graph::SetVideoWindowRect {0 26 1280 741}
09:07:16.395 - * s:55 q:81 tr=10299392 (0) 
09:07:17.789 - Graph::SetVolume 10% -4152db
09:07:17.833 - Graph::SetVolume 20% -2902db
09:07:17.879 - Graph::SetVolume 30% -2171db
09:07:17.959 - Graph::SetVolume 40% -1652db
09:07:18.394 - * s:55 q:81 tr=12032000 (3760) 
09:07:18.846 - Graph::SetVolume 50% -1250db
09:07:18.886 - Graph::SetVolume 60% -921db
09:07:19.242 - Graph::SetVolume 70% -643db
09:07:19.260 - Graph::SetVolume 80% -402db
09:07:19.282 - Graph::SetVolume 90% -190db
09:07:19.606 - Graph::SetVolume 100% 0db
09:07:19.622 - Graph::SetVolume 100% 0db
09:07:19.638 - Graph::SetVolume 100% 0db
09:07:19.670 - Graph::SetVolume 100% 0db
09:07:19.868 - Graph::SetVolume 90% -190db
09:07:19.892 - Graph::SetVolume 80% -402db
09:07:19.898 - Graph::SetVolume 70% -643db
09:07:19.908 - Graph::SetVolume 60% -921db
09:07:19.923 - Graph::SetVolume 50% -1250db
09:07:19.930 - Graph::SetVolume 40% -1652db
09:07:19.962 - Graph::SetVolume 30% -2171db
09:07:20.184 - Graph::SetVolume 20% -2902db
09:07:20.200 - Graph::SetVolume 10% -4152db
09:07:20.208 - Graph::SetVolume 0% -10000db
09:07:20.216 - Graph::SetVolume 0% -10000db
09:07:20.224 - Graph::SetVolume 0% -10000db
09:07:20.240 - Graph::SetVolume 0% -10000db
09:07:20.387 - * s:55 q:81 tr=12705792 (0) 
09:07:20.461 - Graph::SetVolume 0% -10000db
09:07:20.476 - Graph::SetVolume 0% -10000db
09:07:20.484 - Graph::SetVolume 0% -10000db
09:07:20.492 - Graph::SetVolume 0% -10000db
09:07:20.508 - Graph::SetVolume 0% -10000db
09:07:20.524 - Graph::SetVolume 0% -10000db
09:07:22.391 - * s:55 q:81 tr=12802048 (0) 
09:07:23.601 - dvb.subs.Sync byte error!!!
09:07:23.685 - 
09:07:23.685 - Stop engine
09:07:23.686 - DevicesManager::Stop
09:07:23.687 - ChannelGraph5::StopChannel 
09:07:23.687 - PreScanner.Stop
09:07:23.688 - GraphState: Undef by PreScanner.Stop
09:07:23.688 - PlayTimePmtParser::StopParser
09:07:23.689 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
09:07:23.690 - Prereader::ReaderCursor.. OK
09:07:23.697 - ReaderCursor::SetPosition 0 (w=11087148836978688)
09:07:23.698 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
09:07:23.699 - Prereader.StartPlayback after lock
09:07:23.699 - subsm.StopChannel
09:07:23.700 - SSBitmap: SetChannel
09:07:23.701 - SSTeletext: SetChannel page=0 lng
09:07:23.702 - Graph::Stop
09:07:23.742 - VR: Disconnect
09:07:23.744 - Graph::ReleaseAllFilters
09:07:23.745 - release: Audio Renderer - RR - OK.
09:07:23.747 - release: Video Renderer - RR - OK.
09:07:23.755 - release: SubtitleOut - RR - OK.
09:07:23.756 - release: TeletextOut - RR - OK.
09:07:23.758 - release: Microsoft DTV-DVD Video Decoder - RR - OK.
09:07:23.762 - release: Audio decoder - RR - OK.
09:07:23.765 - release: Demultiplexor - RR - OK.
09:07:23.767 - FilterGraph: 1
09:07:23.771 - ChannelGraph5.~CChannelGraph5
09:07:23.772 - PreScanner.Stop
09:07:23.772 - GraphState: Undef by PreScanner.Stop
09:07:23.773 - PlayTimePmtParser::StopParser
09:07:23.773 - subsm.StopChannel
09:07:23.774 - SSBitmap: SetChannel
09:07:23.775 - SSTeletext: SetChannel page=0 lng
09:07:23.775 - Graph::ReleaseAllFilters
09:07:23.776 - ChannelGraph5.~CChannelGraph5 finish
09:07:23.810 - TransponderManager::CloseChannel
09:07:23.811 - TsInputTrafficProcessor.RemoveChannel 8921
09:07:23.811 - ~TransponderChannelSource
09:07:23.821 - TimeShift.DestroyReadingCursor
09:07:23.832 - Api2BaseModule.UpdateAllOpenedChannels 0
09:07:23.834 - PlayTimePmtParser::StopParser
09:07:23.835 - Destroy TSTS filter pid=258
09:07:23.836 - DevicesManager::DestroyTransponderManager
09:07:23.836 - Api2Device::SetChannel - None
09:07:23.837 - ch:0
09:07:23.839 - Api2Device::SetChannel result OK
09:07:23.840 - { -> 0
09:07:23.841 - ~TransponderManager
09:07:23.842 - { -> 0
09:07:23.848 - TimeShift.~TimeShift
09:07:23.849 - HugeMemoryBlocksManager::Free
09:07:23.850 - ModuleList::Free
09:07:23.855 - DevicesManager::Free devices
09:07:23.855 - SDP:Destroy bda.ccaa own=00000000
09:07:23.870 - SDP:after destroy device
09:07:23.871 - SDP:Destroy bda.cca9 own=00000000
09:07:23.885 - SDP:after destroy device
09:07:23.885 - SDP:Destroy netclient.0 own=00000000
09:07:23.885 - SDP:after destroy device
09:07:23.885 - DevicesManager::Free RC
09:07:23.965 - plugin unloaded
09:07:23.965 - MDPlugin::Exit OK
09:07:23.965 - Engine stopped
09:07:24.065 - HugeMemoryBlocksManager::FreeAll
