﻿01:34:01.486 - Start logging...
01:34:01.611 - ExceptionFilter::Init. restart=1
01:34:02.330 - DevicesManager::EnableCISupport
01:34:02.330 - CAVManager::SetSettings
01:34:02.346 - DevicesManager::DestroyAllTransponderManager
01:34:02.408 - SetDefaultNullCodecs
01:34:02.455 - SetDefaultNullCodecs
01:34:02.496 - DevicesManager::Start
01:34:02.496 - AVManager::Init
01:34:02.511 - RemoveAllCreateBlank needBlank1
01:34:02.527 - [101]OnChangeCurrentGraph2 -RemoveAllBlank
01:34:02.605 - VR: GUI
01:34:02.652 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:34:02.840 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:34:02.840 - AV:UpdateAudioParamsAfterActive
01:34:02.855 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:34:02.871 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:34:03.418 - DevicesManager::StartDevices...
01:34:03.418 - device netclient.0 01:34:03.449 - dev: Created  netclient.0 09343788
OK
01:34:03.480 - Api2BaseModule.GetAdditionInfo
01:38:29.431 - 
01:38:29.431 - 
01:38:29.446 - ########################### Mark of start channel changing.###########################
01:38:29.462 - 
01:38:29.477 - GraphManager::MainChangeChannel Deluxe Music  1 provs
01:38:29.493 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
01:38:29.509 - SetChannelLogic::SetChannel 0
01:38:29.524 - SetChannelLogic::SetNormalChannel
01:38:29.540 - DevicesManager::CreateCloneDevice
01:38:29.556 - DevicesManager::CreateCloneDevice OK - founded in list
01:38:29.571 - TransponderManager
01:38:29.587 - CTransponderManager::InitOnNewDevice 0C40C02C
01:38:29.602 - SetOwner N -> Incoming Stream Cache
01:38:29.618 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
01:38:29.634 - Normal TsInputTrafficProcessor.
01:38:29.649 - FilterManager::InitOnNewDevice 1
01:38:29.665 - TS router+MarkerFinder -> InputTrafficProcessor
01:38:29.681 - Incoming Stream Cache -> TS router+MarkerFinder
01:38:29.696 - InputTrafficProcessor -> Router to timeshift
01:38:29.712 - InputTrafficProcessor(FM) -> FilterManager
01:38:29.727 - ModuleList::Free
01:38:29.743 - ChannelGraph5.CChannelGraph5
01:38:29.759 - TransponderManager::AddChildChannel
01:38:29.774 - TimeShift.TimeShift
01:38:29.790 - TimeShift.Create 400 mbytes 
01:38:29.790 - ts:One channel
01:38:29.790 - HugeMemoryBlocksManager::GetBlock 400 mbytes OK
01:38:29.790 - TransponderManager::CreateTimeshiftBuffer OK
01:38:29.806 - TimeShift.CreateReadingCursor
01:38:29.821 - TransponderChannelSource.Plugins 00847D58 csa 00000000
01:38:29.837 - TsInputTrafficProcessor.AddChannel 0
01:38:29.852 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
01:38:29.884 - ChannelGraph5.SetActiveState 1
01:38:29.884 - TransponderManager::SelectChannel
01:38:29.899 - TsInputTrafficProcessor.SelectChannel 0
01:38:29.915 - Api2BaseModule.UpdateAllOpenedChannels 0
01:38:29.931 - AV:UpdateAudioParamsAfterActive
01:38:29.946 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:38:29.962 - Graph::SetAudioChannelMask
01:38:29.977 - 
01:38:29.993 - TransponderManager::SetTransponder (CH) 0 Deluxe Music
01:38:30.009 - *Transport TS
01:38:30.024 - Api2Device::SetChannel http://am01.spr24.net/14029/mpegts?token=4u6VY92quYvYVm
01:38:30.134 - Api2Device::SetChannel result OK
01:38:30.134 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
01:38:30.149 - TsInputTrafficProcessor.OpenTransponder 1
01:38:30.165 - Api2BaseModule.OnTunerStateChanged Connecting
01:38:30.165 - FilterManager::OnSetTransponder SetChannel
01:38:30.181 - TimeShift.Reset
01:38:30.196 - TimeShift.Cursor reset
01:38:30.212 - ReaderCursor::Reset.. OK
01:38:30.243 - Prereader.Reset ...-OK
01:38:30.274 - Create EPGparser on SetChannel
01:38:30.290 - DvbEpgParser::SetChannel ID=3cf00002.fd913380fa23c515.1
01:38:30.321 - DvbEpgParser::mode normal pos=0
01:38:30.337 - ModuleList::ChangeChannel 0
01:38:30.352 - [102]ChannelGraph5::StartChannel Deluxe Music
01:38:30.368 - 
01:38:30.384 - [102]       #################### Start stream. (0.953ms) #######################
01:38:30.399 - 
01:38:30.431 - Api2BaseModule.OnTunerStateChanged Preparing
01:38:30.493 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
01:38:30.493 - TimeShift.Cursor SetChannel::v 0 (0)
01:38:30.509 - Api2BaseModule.OnTunerStateChanged Playing
01:38:30.509 - ReaderCursor::SetChannel.. OK
01:38:30.555 - ReaderCursor::SetPosition 0 (w=0)
01:38:30.571 - Prereader.SetChannel..
01:38:30.587 - Prereader.SetChannel unmask 1
01:38:30.602 - Plugin::SetChannel 1
01:38:30.618 - TransponderManager::SelectChannel
01:38:30.634 - TsInputTrafficProcessor.SelectChannel 1
01:38:30.649 - Api2BaseModule.UpdateAllOpenedChannels 1
01:38:30.665 - TsInputTrafficProcessor.UpdateChannel 1
01:38:30.680 - GraphState: Undef by StartChannelIsReady
01:38:30.696 - PreScanner.StartChannelIsReady net=1 parseall=1
01:38:30.712 - VideoMediaTypeDetection: h.264
01:38:30.727 - SetMarkerAudioLngStream Gen New stream
01:38:30.743 - SimpleTsDemux::Init apid=221, vpid=211
01:38:30.774 - GraphState: RequestPATScan by StartChannelIsReady TS
01:38:30.790 - Api2BaseModule.Filters_EnabledFullTS 1
01:38:30.805 - PlayTimePATParser.OpenParser 1
01:38:30.821 - Open TSTS filter pid=0 data=0
01:38:30.837 - TSTS cur size=0
01:38:30.852 - Graph::SetVideoRect {8 8 712 568}
01:38:30.884 - [102]OnChangeCurrentGraph2 -endOfSetChannel
01:38:30.899 - ChannelGraph5.SetActiveState 1
01:38:30.993 - Graph::SetVideoWindowRect {33 0 166 100}
01:38:30.993 - Graph::UpdateRenderMode
01:38:31.087 - GraphManager::MultiChannelRendering
01:38:31.087 - ChannelGraph5.SetActiveState 1
01:38:31.115 - Graph::SetVideoWindowRect {249 0 1351 827}
01:38:31.355 - OK. 102
01:38:31.724 - Api2BaseModule.OnTunerStateChanged Playing
01:38:31.765 - Api2BaseModule.OnTunerStateChanged Connecting
01:38:31.807 - * s:0 q:0 tr=0 (0) Connecting
01:38:32.013 - Api2BaseModule.OnTunerStateChanged Preparing
01:38:32.092 - Api2BaseModule.OnTunerStateChanged Playing
01:38:32.191 - TransportMarker: TS
01:38:32.191 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
01:38:32.209 - --SwitchEngineTransport TS > TS
01:38:32.225 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=419430400
01:38:32.242 - PreScanner.OnTransportMarker TS
01:38:32.259 - --SwitchEngineTransport end
01:38:32.747 - * s:100 q:100 tr=376 (0) Preparing
01:38:33.007 - OSDManager::Window_Add
01:38:33.024 - OSDManager::Window_Show
01:38:33.140 - OSDManager::Window_Show
01:38:33.159 - OSDManager::Window_Show
01:38:33.751 - * s:100 q:100 tr=188 (0) Preparing
01:38:34.759 - * s:100 q:100 tr=188 (0) Preparing
01:38:35.751 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:36.755 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:37.762 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:38.770 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:39.775 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:40.768 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:41.798 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:42.793 - * s:100 q:100 tr=0 (0) PreparingButTooLong
01:38:43.802 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:44.805 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:45.811 - * s:100 q:100 tr=188 (0) PreparingButTooLong
01:38:45.895 - 
01:38:45.896 -        #################### First traffic. (16.469ms) #######################
01:38:45.918 - 
01:38:45.937 - PlayTimePATParser.EndTask
01:38:45.999 - PreScanner.OnAfterScanPat count=2 {1;4095}
01:38:46.000 - Api2BaseModule.Filters_EnabledFullTS 0
01:38:46.027 - GraphState: RequestPMTScan by OnAfterScanPat
01:38:46.028 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
01:38:46.052 - Open TSTS filter pid=4095 data=2
01:38:46.072 - TSTS cur size=441612
01:38:46.096 - TSTS quick finish
01:38:46.113 - PreScanner.OnAfterScanPmt pmt=4095
01:38:46.133 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
01:38:46.151 - PlayTimePmtParser::StopParser
01:38:46.378 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
01:38:46.379 - TimeShift.Cursor SetChannel::v 1694820 (1694820)
01:38:46.397 - Prereader.SetChannel unmask 1
01:38:46.415 - Plugin::SetChannel 1
01:38:46.431 - TransponderManager::SelectChannel
01:38:46.448 - TsInputTrafficProcessor.SelectChannel 1
01:38:46.465 - Api2BaseModule.UpdateAllOpenedChannels 1
01:38:46.484 - TsInputTrafficProcessor.UpdateChannel 1
01:38:46.486 - PreScanner.StartChannelIsReady net=1 parseall=0
01:38:46.487 - LngDefaultSelector.try select audio pid=221 type=1 lng=7693668-deu(goh,goh,rus) l=99
01:38:46.488 - ChannelGraph5::AfterDetectChannelParams
01:38:46.490 - VideoMediaTypeDetection: h.264
01:38:46.491 - SimpleTsDemux::Init apid=221, vpid=211
01:38:46.493 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
01:38:46.511 - demux: ProgDemux
01:38:46.532 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
01:38:46.546 - Prereader::ReaderCursor.. OK
01:38:46.565 - ReaderCursor::SetPosition 0 (w=10555036748742656)
01:38:46.588 - Prereader.StartPlayback Push buffering=0 recv=09336978 [StartChannelHelperBase(sl)]
01:38:46.606 - Prereader.StartPlayback after lock
01:38:46.623 - PreScanner.StartPmtParserIfNeed
01:38:46.640 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
01:38:46.658 - Open TSTS filter pid=4095 data=2
01:38:46.675 - TSTS cur size=2750816
01:38:46.624 - VideoParser::First packet 73361 h.264
01:38:46.712 - h264 detected 0 ms
01:38:46.729 - AudioParser::First packet 1536
01:38:46.747 - AudioParser::packet 1536
01:38:46.763 - AudioParser::AC3 detected
01:38:46.780 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
01:38:46.797 - PreScanner.Post_InitRender [ReceiveTraffic]
01:38:46.807 - * s:100 q:100 tr=3119296 (0) Preparing
01:38:46.935 - [102]sm: TvInitRenderer 1
01:38:46.935 - ChannelGraph5.uiThread_CreateGraphAfterDetectOfMediaTypes
01:38:46.953 - CalculBufferingSize prov=0C40D46C marker=1 request=1
01:38:46.970 - CalculBuffering 2000ms br=2000kb to 500 kbytes. By type
01:38:46.987 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
01:38:47.003 - Prereader::ReaderCursor.. OK
01:38:47.036 - ReaderCursor::SetPosition 0 (w=14131249857691648)
01:38:47.052 - Prereader.StartPlayback Pull buffering=512000 recv=00000000 [EnablePullModeAndStartPlayback]
01:38:47.069 - Prereader.StartPlayback after lock
01:38:47.085 - Buffering/prereader size 500/3906 kbytes
01:38:47.103 - ------sGetVideoParams
01:38:47.120 - ChannelGraph5.CreateInterfacesAndRenderGraph
01:38:47.129 - subsm.StartChannel demux=ProgDemux tr=TS
01:38:47.155 - subsm.RecreateSubtitlesParsers 117DA698 ts=1 subindex=-1
01:38:47.172 - subsm.Set Teletext parser 0
01:38:47.189 - subsm.RecreateSubtitlesParsers end
01:38:47.205 - subs: OnSetChannel
01:38:47.222 - CreateCustomGraph
01:38:47.239 - VideoType:H.264/AVC ffmpeg=0
01:38:47.255 - 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:38:47.272 - Add video codec ee30215d-164f-4a92-a4eb-9d4c13390f9f
01:38:47.349 - OK
01:38:47.350 - CChannelGraph5::CreateRender 10
01:38:47.370 - VR: EVR Custom
01:38:47.388 - VR: create new render
01:38:47.470 - VR: Add video renderer Video Renderer - OK
01:38:47.471 - CreateAudioPartOfGraph
01:38:47.489 - AudioType:AC3 
01:38:47.506 - Audio/AC3 : WaveFormatEx (2ch 48000 0bit formatTag= 0x0)
01:38:47.525 - Add audio codec 4a2286e0-7bef-11ce-9bd9-0000e202599c - Pin for connection not found! 0x80040207
01:38:47.565 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:Panasonic-TV (Аудио Intel(R) для дисплеев) OK (AC3)
01:38:47.566 - Added audio renderer
01:38:47.644 - Try to found any codec before renderer... OK.
01:38:47.645 - Graph::AfterRender
01:38:47.669 - GraphState: GraphStarted by CreateInterfacesAndRenderGraph
01:38:47.687 - AV:UpdateAudioParamsAfterActive
01:38:47.704 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
01:38:47.721 - Graph::SetVolume 100% 0db
01:38:47.742 - Graph::SetAudioChannelMask
01:38:47.756 - Graph::Run
01:38:47.880 - Graph::Run 1
01:38:47.880 - Graph::SetPictureParams
01:38:47.897 - Graph::SetVideoRect {16 16 1264 1068}
01:38:47.917 - Graph::SetVideoWindowRect {66 0 1534 827}
01:38:47.940 - 
01:38:47.942 - [0]########################### Mark of finish channel changing. (18.515ms) #############
01:38:47.943 - 
01:38:48.357 - 
01:38:48.357 - [0]########################### First video frame. (18.922ms) #############
01:38:48.378 - 
01:38:48.463 - CChannelGraph5::HideSubtitles
01:38:48.530 - * s:0 q:100 tr=218456 (0) Playing
01:38:50.288 - * s:99 q:100 tr=519820 (0) Playing
01:38:50.835 - * s:0 q:100 tr=13348 (0) Playing
01:38:51.466 - * s:51 q:100 tr=268840 (0) Playing
01:38:52.084 - * s:100 q:100 tr=259064 (0) Playing
01:38:53.253 - * s:50 q:100 tr=264516 (0) Playing
01:38:53.958 - OSDManager::Window_Show
01:38:53.959 - OSDManager::Window_Delete
01:38:54.026 - * s:95 q:100 tr=231428 (0) Playing
01:38:54.645 - * s:100 q:100 tr=1444028 (0) Playing
01:38:55.142 - * s:100 q:100 tr=1165600 (0) Playing
01:38:55.763 - * s:100 q:100 tr=1828676 (0) Playing
01:38:56.457 - * s:0 q:100 tr=647472 (0) Playing
01:38:57.922 - * s:90 q:100 tr=468872 (0) Playing
01:38:58.826 - * s:16 q:100 tr=175216 (0) Playing
01:38:59.833 - * s:45 q:100 tr=152092 (0) Playing
01:39:00.828 - * s:45 q:100 tr=188 (0) Playing
01:39:01.777 - * s:100 q:100 tr=329188 (0) Playing
01:39:02.833 - * s:87 q:100 tr=454396 (0) Playing
01:39:03.775 - * s:100 q:100 tr=108852 (0) Playing
01:39:04.345 - * s:100 q:100 tr=638824 (0) Playing
01:39:04.867 - * s:70 q:100 tr=1391200 (0) Playing
01:39:05.674 - * s:100 q:100 tr=800880 (0) Playing
01:39:06.442 - * s:100 q:100 tr=1165036 (0) Playing
01:39:07.405 - * s:100 q:100 tr=1311112 (0) Playing
01:39:07.905 - * s:0 q:100 tr=946016 (0) Playing
01:39:08.858 - * s:69 q:100 tr=1163344 (0) Playing
01:39:09.413 - * s:100 q:100 tr=159424 (0) Playing
01:39:10.867 - * s:71 q:100 tr=372616 (0) Playing
01:39:11.628 - * s:71 q:100 tr=0 (0) Playing
01:39:12.590 - * s:100 q:100 tr=216200 (0) Playing
01:39:13.866 - * s:64 q:100 tr=336332 (0) Playing
01:39:14.462 - * s:100 q:100 tr=250228 (0) Playing
01:39:14.988 - * s:100 q:100 tr=545388 (0) Playing
01:39:15.576 - * s:0 q:100 tr=873072 (0) Playing
01:39:16.316 - * s:0 q:100 tr=1017644 (0) Playing
01:39:16.346 - * s:0 q:100 tr=-73132 (0) Playing
01:39:16.856 - * s:28 q:100 tr=1240988 (0) Playing
01:39:17.980 - * s:100 q:100 tr=984180 (0) Playing
01:39:18.629 - * s:14 q:100 tr=75012 (0) Playing
01:39:19.866 - * s:30 q:100 tr=84036 (0) Playing
01:39:20.849 - * s:66 q:100 tr=185744 (0) Playing
01:39:21.856 - * s:0 q:100 tr=209808 (0) Playing
01:39:22.849 - * s:36 q:100 tr=186872 (0) Playing
01:39:23.454 - * s:0 q:100 tr=1464144 (0) Playing
01:39:24.326 - * s:100 q:100 tr=1487080 (0) Playing
01:39:24.854 - * s:71 q:100 tr=369420 (0) Playing
01:39:25.469 - * s:100 q:100 tr=1497984 (0) Playing
01:39:26.339 - * s:100 q:100 tr=1169548 (0) Playing
01:39:27.079 - * s:100 q:100 tr=1113900 (0) Playing
01:39:27.767 - * s:0 q:100 tr=622468 (0) Playing
01:39:28.634 - * s:0 q:100 tr=0 (0) Playing
01:39:29.881 - * s:49 q:100 tr=254928 (0) Playing
01:39:30.876 - * s:97 q:100 tr=246656 (0) Playing
01:39:31.412 - * s:100 q:100 tr=83660 (0) Playing
01:39:32.462 - * s:100 q:100 tr=539184 (0) Playing
01:39:33.172 - * s:100 q:100 tr=1125368 (0) Playing
01:39:33.857 - * s:100 q:100 tr=1162028 (0) Playing
01:39:34.620 - * s:100 q:100 tr=1168044 (0) Playing
01:39:34.957 - ChannelGraph5.~CChannelGraph5
01:39:34.979 - Graph::Stop
01:39:35.013 - Before Stop ...01:39:35.031 - OnGraphStop ...01:39:35.062 - PreScanner.Stop
01:39:35.084 - GraphState: Undef by PreScanner.Stop
01:39:35.123 - PlayTimePmtParser::StopParser
01:39:35.148 - subsm.StopChannel
01:39:35.178 - VR: Disconnect
01:39:35.229 - Graph::ReleaseAllFilters
01:39:35.247 - release: Prog Audio renderer - 01:39:35.311 - * s:100 q:100 tr=1316940 (0) PreparingButTooLong
RR - OK.
01:39:35.399 - release: Video Renderer - RR - OK.
01:39:35.471 - release: Video decoder  - RR - OK.
01:39:35.520 - release: LAV Audio Decoder - RR - OK.
01:39:35.578 - FilterGraph: 1
01:39:35.615 - ChannelGraph5.~CChannelGraph5 finish
01:39:35.638 - TransponderManager::CloseChannel
01:39:35.654 - TsInputTrafficProcessor.RemoveChannel 1
01:39:35.685 - ~TransponderChannelSource
01:39:35.825 - TimeShift.DestroyReadingCursor
01:39:35.825 - Api2BaseModule.UpdateAllOpenedChannels 0
01:39:35.841 - Destroy TSTS filter pid=0
01:39:35.872 - PlayTimePmtParser::StopParser
01:39:35.888 - Destroy TSTS filter pid=4095
01:39:35.904 - DevicesManager::DestroyTransponderManager
01:39:35.919 - Api2Device::SetChannel - None
01:39:36.029 - Api2Device::SetChannel result OK
01:39:36.029 - SetOwner N -> 0
01:39:36.044 - SetOwner SetTransponder null
01:39:36.060 - ~TransponderManager
01:39:36.075 - SetOwner N -> 0
01:39:36.091 - SetOwner SetTransponder null
01:39:36.138 - TimeShift.~TimeShift
01:39:36.138 - HugeMemoryBlocksManager::Free
01:39:36.154 - ModuleList::Free
01:39:36.169 - [103]OnChangeCurrentGraph2 -CloseGraph
01:39:36.247 - VR: GUI
01:39:36.263 - GraphManager::MultiChannelRendering
01:39:55.239 - 
01:39:55.239 - Stop engine
01:39:55.255 - DevicesManager::Stop
01:39:55.271 - DevicesManager::Free devices
01:39:55.286 - SDP:Destroy netclient.0 own=00000000
01:39:55.333 - SDP:after destroy device
01:39:55.333 - DevicesManager::Free RC
01:39:55.349 - Engine stopped
01:39:55.411 - 
01:39:55.411 - Stop engine
01:39:55.442 - DevicesManager::Stop
01:39:55.458 - DevicesManager::Free devices
01:39:55.474 - Engine stopped
01:39:55.739 - HugeMemoryBlocksManager::FreeAll
