﻿23:29:22.514 -  ProgDVBVideoWindow    NotForceAPforRadio WinGetStateInMainThread
23:29:22.515 - InterfaceApiManagerWnd CreateWnd
23:29:22.517 - DevicesManager::Start
23:29:22.517 - AVManager::Init
23:29:22.718 - SetDefaultNullCodecs
23:29:22.723 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 074FF84C
23:29:22.725 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 074FD124
23:29:22.728 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 074FF84C
23:29:22.731 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 074FD124
23:29:22.733 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 07513064
23:29:22.734 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0751349C
23:29:22.735 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 07513064
23:29:22.736 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0751349C
23:29:22.736 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 07513064
23:29:22.737 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0751349C
23:29:22.737 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 07513064
23:29:22.737 - RemoveAllCreateBlank needBlank0
23:29:22.740 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module - 10000000
23:29:22.787 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\ProgMediaServer.Module - 0CC00000
23:29:22.803 - DevicesManager::EnableCISupport
23:29:22.803 - CAVManager::SetSettings
23:29:22.803 - DevicesManager::DestroyAllTransponderManager
23:29:22.804 - SetDefaultNullCodecs
23:29:22.807 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 0C70F904
23:29:22.809 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 0C70F904
23:29:22.811 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 0C70F904
23:29:22.813 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVAudio.ax 0C70F904
23:29:22.813 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.814 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.815 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.816 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.816 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.817 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.818 - Codec from file: C:\Program Files (x86)\ProgDVB Professional\LAVVideo.ax 0C70F904
23:29:22.820 - DevicesManager::StartDevices...
23:29:22.820 - DevicesManager::Before start:
23:29:22.820 - device netclient.0 23:29:22.822 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\netclient.Device - 641A0000
23:29:22.825 - dev: UpdateNetworkRecommendations  750x492 -1x-1
23:29:22.826 - dev: Created  netclient.0 0C617798
OK
23:29:22.827 - Api2BaseModule.GetAdditionInfo
23:29:22.827 - Api2BaseModule.GetAdditionInfo OK
23:29:22.832 - [-20]AV:UpdateAudioParamsAfterActive Skip
23:29:22.834 - DevicesManager::EnableCISupport
23:29:22.834 - Api2Device::CI3_EnableCISupport
23:29:22.835 - CAVManager::SetSettings
23:29:22.835 - Api2BaseModule.SetDialogSettings
23:29:22.835 - Api2BaseModule.SetDialogSettings
23:29:22.943 - Api2BaseModule.OnDisplaySize
23:29:22.943 - dev: UpdateNetworkRecommendations  1936x1088 -1x-1
23:29:23.012 - Api2BaseModule.OnDisplaySize
23:29:23.012 - dev: UpdateNetworkRecommendations  1936x1066 -1x-1
23:29:23.319 - 
23:29:23.319 - 
23:29:23.320 - ########################### Mark of start channel changing.###########################
23:29:23.320 - 
23:29:23.320 - GraphManager::MainChangeChannel LBC News  1 provs
23:29:23.320 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:29:23.321 - SetChannelLogic::SetChannel 0
23:29:23.321 - SetChannelLogic::SetNormalChannel
23:29:23.321 - DevicesManager::CreateCloneDevice
23:29:23.322 - DevicesManager::CreateCloneDevice OK - founded in list
23:29:23.322 - TransponderManager
23:29:23.322 - CTransponderManager::InitOnNewDevice 0C710B3C
23:29:23.323 - SetOwner N -> Incoming Stream Cache
23:29:23.323 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
23:29:23.323 - Normal TsInputTrafficProcessor.
23:29:23.324 - FilterManager::InitOnNewDevice 1
23:29:23.325 - TS router+MarkerFinder -> InputTrafficProcessor
23:29:23.326 - Incoming Stream Cache -> TS router+MarkerFinder
23:29:23.326 - InputTrafficProcessor -> Router to timeshift
23:29:23.326 - InputTrafficProcessor(FM) -> FilterManager
23:29:23.327 - ModuleList::Free
23:29:23.327 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module - 10000000
23:29:23.328 - Api2BaseModule::GetCurrentDeviceInfo
23:29:23.328 - ModuleList::Load C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module OK
23:29:23.329 - Add timer 1000 BaseTransponderManaer
23:29:23.329 - TransponderManager::AddChildChannel
23:29:23.329 - TimeShift.TimeShift
23:29:23.330 - TimeShift.Create 40 mbytes 
23:29:23.330 - ts:Disabled
23:29:23.330 - HugeMemoryBlocksManager::GetBlock 40 mbytes OK
23:29:23.331 - TransponderManager::CreateTimeshiftBuffer OK
23:29:24.546 - TimeShift.CreateReadingCursor
23:29:24.547 - TransponderChannelSource.Plugins 007E9728 c 00000000
23:29:24.548 - TsInputTrafficProcessor.AddChannel 0
23:29:24.548 - AndroidChannelPlayback created TestMode=0
23:29:24.549 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
23:29:24.551 - [101] ChannelGraph.SetActiveState 1 (old=0)
23:29:24.551 - TransponderManager::SelectChannel
23:29:24.552 - TsInputTrafficProcessor.SelectChannel 0
23:29:24.553 - Api2BaseModule.UpdateAllOpenedChannels 0
23:29:24.553 - **** StopChannel time 1.219ms
23:29:24.554 - 
23:29:24.555 - TransponderManager::SetTransponder (CH) 0 LBC News
23:29:24.555 - *Transport TS
23:29:24.556 - Api2Device::SetChannel http://icecast.thisisdax.com/LBCNewsLondonMP3.m3u
23:29:24.559 - Api2Device::SetChannel result OK
23:29:24.559 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:24.559 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:29:24.560 - TsInputTrafficProcessor.OpenTransponder 1
23:29:24.560 - FilterManager::OnSetTransponder SetChannel
23:29:24.561 - TimeShift.Reset
23:29:24.561 - TimeShift.Cursor reset
23:29:24.561 - ReaderCursor::Reset.. OK
23:29:24.562 - Prereader.Reset ...-OK
23:29:24.563 - Create EPGparser on SetChannel
23:29:24.563 - DvbEpgParser::SetChannel ID=4a000002.376bdf855581d8f9.abcd
23:29:24.564 - DvbEpgParser::mode normal pos=0
23:29:24.564 - ModuleList::ChangeChannel 0
23:29:24.565 - **** SetTransponder time 0.016ms
23:29:24.565 - [101]ChannelGraph5::StartChannel LBC News
23:29:24.566 - [101]AV:UpdateAudioParamsAfterActive
23:29:24.566 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:24.566 - GR:UpdateAudioProcessor old=0 new=0
23:29:24.567 - CPCG SetVolume 00000000 100
23:29:24.567 - 
23:29:24.568 - [101]       #################### Start stream. (1.235ms) #######################
23:29:24.568 - 
23:29:24.569 - SCH 0
23:29:24.569 - TCS set 0
23:29:24.569 - TCS set 1
23:29:24.570 - TCS set 2
23:29:24.571 - TCS set 3
23:29:24.571 - TCS set 4
23:29:24.571 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:24.571 - TimeShift.Cursor SetChannel::v 0 (0)
23:29:24.572 - ReaderCursor::SetChannel.. OK
23:29:24.572 - ReaderCursor::SetPosition 0 (w=0)
23:29:24.573 - Prereader.SetChannel..
23:29:24.573 - PMT convertor
23:29:24.573 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:24.574 - PPC.by mask. onlyV1A=0 No change PMT ,0
23:29:24.574 - TCS set 5
23:29:24.574 - TCS set 6
23:29:24.575 - Plugin::SetChannel 43981
23:29:24.575 - TCS set 7
23:29:24.575 - TransponderManager::SelectChannel
23:29:24.576 - TsInputTrafficProcessor.SelectChannel 43981
23:29:24.576 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:24.576 - TCS set 8
23:29:24.577 - TsInputTrafficProcessor.UpdateChannel 43981
23:29:24.577 - TCS set 9
23:29:24.577 - SCH 1
23:29:24.578 - GraphState: Undef by StartChannelIsReady
23:29:24.578 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:24.578 - SimpleTsDemux::Init apid=0, vpid=0
23:29:24.579 - GraphState: RequestPATScan by StartChannelIsReady TS
23:29:24.579 - Api2BaseModule.Filters_EnabledFullTS 1
23:29:24.580 - PlayTimePATParser.OpenParser 43981
23:29:24.580 - Open TSTS filter pid=0 data=0
23:29:24.580 - TSTS cur size=0
23:29:24.581 - SCH 2
23:29:24.581 - **** StartChannel time 0.015ms
23:29:24.581 - [101]OnChangeCurrentGraph2 -endOfSetChannel
23:29:24.581 - [101] ChannelGraph.SetActiveState 1 (old=1)
23:29:24.641 -     CreateVideoWindow Skiped! No Graph of control
23:29:24.651 - GraphManager::SelectGraph 101
23:29:24.666 - Api2BaseModule.OnDisplaySize
23:29:24.666 - dev: UpdateNetworkRecommendations  1936x1088 -1x-1
23:29:24.696 - Api2BaseModule.OnDisplaySize
23:29:24.696 - dev: UpdateNetworkRecommendations  1920x1080 -1x-1
23:29:24.732 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:24.734 - Api2BaseModule.OnDisplaySize
23:29:24.734 - dev: UpdateNetworkRecommendations  1936x1066 -1x-1
23:29:24.735 - [101]**** SetChannel is finished (OK). Duration 1.406ms
23:29:24.744 - * s:0 q:0 tr=0 (0) - ntt-1 dmx0 -1 Connecting
23:29:24.752 - ------sGetVideoParams
23:29:24.803 - Api2BaseModule.OnTunerStateChanged Playing
23:29:24.828 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:24.835 - CPCG SetVolume 00000000 0
23:29:24.881 - * s:0 q:0 tr=0 (0) - ntt-1 dmx0 -1 Connecting
23:29:24.893 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:24.964 - Api2BaseModule.OnTunerStateChanged Playing
23:29:24.966 - TsInputTrafficProcessor - switch to AV mode
23:29:24.966 - 
23:29:24.966 -        #################### First traffic. (1.641ms) #######################
23:29:24.967 - 
23:29:24.967 - TransportMarker: Radio packets
23:29:24.967 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:24.967 - --SwitchEngineTransport TS > ITV
23:29:24.967 - PreScanner.Stop
23:29:24.968 - GraphState: Undef by PreScanner.Stop
23:29:24.968 - Api2BaseModule.Filters_EnabledFullTS 0
23:29:24.968 - Incoming Stream Cache -> 0
23:29:24.968 - ModuleList::Free
23:29:24.969 - TrafficProcessingScheme_CreateForNewEngineTransport 2 ss=2
23:29:24.970 - InputTrafficHelper -> ITV2Timeshift
23:29:24.970 - TS router+MarkerFinder -> InputTrafficHelper
23:29:24.970 - Send marker again
23:29:24.970 - Incoming Stream Cache -> InputTrafficHelper
23:29:24.970 - ReindexForItvAfterTransport -> TimeShift reindex
23:29:24.971 - TimeShift.Cursor reset
23:29:24.971 - ReaderCursor::Reset.. OK
23:29:24.971 - Prereader.Reset ...-OK
23:29:24.994 - Prereader.Exit23:29:24.996 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:29:24.996 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:24.996 - SCH 0
23:29:24.996 - TCS set 0
23:29:24.997 - TCS set 1
23:29:24.997 - TCS set 2
23:29:24.997 - TCS set 3
23:29:24.997 - TCS set 4
23:29:24.997 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
23:29:24.997 - TimeShift.Cursor SetChannel::v 0 (27720)
23:29:24.998 - ReaderCursor::SetChannel.. OK
23:29:24.998 - ReaderCursor::SetPosition 0 (w=119056493445120)
23:29:24.998 - Prereader.SetChannel..
23:29:24.998 - Prereader.SetChannel unmask 1
23:29:24.999 - TCS set 5
23:29:24.999 - TCS set 6
23:29:24.999 - Plugin::SetChannel 43981
23:29:24.999 - TCS set 7
23:29:24.999 - TransponderManager::SelectChannel
23:29:24.999 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:25.000 - TCS set 8
23:29:25.000 - TCS set 9
23:29:25.000 - SCH 1
23:29:25.000 - GraphState: Undef by StartChannelIsReady
23:29:25.000 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:25.000 - GraphState: RequestTransponderMark by StartChannelIsReady ITV
23:29:25.001 - SCH 2
23:29:25.001 - PreScanner.OnTransportMarker Radio packets
23:29:25.001 - EngineTransportsParser -> EngineTransportsParserRouter
23:29:25.001 - !marker: audio {mp3-parsing} video {--none} items( MediaType:0:len=0 )
23:29:25.002 - GraphState: RequestMediaTypeDetection by OnTransportMarker Not ready
23:29:25.002 - SCH 0
23:29:25.002 - TCS set 0
23:29:25.002 - TCS set 1
23:29:25.002 - TCS set 2
23:29:25.002 - TCS set 3
23:29:25.003 - TCS set 4
23:29:25.003 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
23:29:25.003 - TimeShift.Cursor SetChannel::v 27720 (27720)
23:29:25.003 - Prereader.SetChannel unmask 1
23:29:25.003 - TCS set 5
23:29:25.003 - TCS set 6
23:29:25.004 - Plugin::SetChannel 43981
23:29:25.004 - TCS set 7
23:29:25.004 - TransponderManager::SelectChannel
23:29:25.004 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:25.004 - TCS set 8
23:29:25.004 - TCS set 9
23:29:25.005 - SCH 1
23:29:25.005 - PreScanner.StartChannelIsReady net=1 parseall=0
23:29:25.005 - SCH 2
23:29:25.005 - SCH 3
23:29:25.006 - LngDefaultSelector.try select audio pid=0 type=1 lng=0-(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:25.006 - ChannelGraph::AfterDetectChannelParams
23:29:25.006 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:29:25.006 - TimeShift.Cursor PushMode 1 1 (pos=27) [StartChannelHelperBase(sl)]
23:29:25.006 - Prereader::ReaderCursor.. OK
23:29:25.007 - ReaderCursor::SetPosition 27720 (w=119056493445120)
23:29:25.007 - Prereader.StartPlayback Push buffering=0 recv=0C80CD9C [StartChannelHelperBase(sl)]
23:29:25.007 - Prereader.StartPlayback after lock
23:29:25.007 - PreScanner.StartPmtParserIfNeed
23:29:25.008 - --SwitchEngineTransport end
23:29:25.026 - PreScanner.OnFirstItvTraffic
23:29:25.026 - AudioParser::First packet 2000
23:29:25.026 - AudioParser::MP3 detected
23:29:25.026 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:29:25.027 - PreScanner.Post_InitRender [ReceiveTraffic]
23:29:25.027 - 
23:29:25.027 - [101]       #################### Init Render. (1.703ms) #######################
23:29:25.027 - 
23:29:25.028 - selector PlaybackTag=none videocodec=FFmpegS/ ts=0
23:29:25.028 - Radio
23:29:25.028 - Channel PlaybackWay after check FFmpegS
23:29:25.029 - GraphState: GraphStarted by AsyncInitRender
23:29:25.029 - CalculBufferingSize prov=0C71234C marker=1 request=1
23:29:25.029 - CalculBuffering 800ms br=48kb to 4 kbytes. from marker
23:29:25.029 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:29:25.029 - Prereader::ReaderCursor.. OK
23:29:25.030 - ReaderCursor::SetPosition 0 (w=244503898226688)
23:29:25.030 - Prereader.StartPlayback Pull buffering=4915 recv=00000000 [EnablePullModeAndStartPlayback]
23:29:25.030 - Prereader.StartPlayback after lock
23:29:25.030 - Buffering/prereader size 4/976 kbytes
23:29:25.031 - Not same player! old 0 new 3 prev 0
23:29:25.031 - ------sGetVideoParams
23:29:25.032 - Create FFMpegTvPlayer
23:29:25.033 - ITV demux -> ItvCorrectionDemux
23:29:25.033 - before render create or reset m=3 radio=1
23:29:25.034 - [101]AV:UpdateAudioParamsAfterActive
23:29:25.034 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:25.034 - GR:UpdateAudioProcessor old=0 new=0
23:29:25.034 - CPCG SetVolume 0DCED4F0 0
23:29:25.035 - subsm.StartChannel demux=FFmpeg tr=AVPackets
23:29:25.035 - subsm.RecreateSubtitlesParsers 0DCED508 ts=0 subindex=-1 st=-1
23:29:25.036 - subsm.RecreateSubtitlesParsers end
23:29:25.036 - subs: OnSetChannel
23:29:25.037 - ------sGetVideoParams
23:29:25.074 - * s:100 q:100 tr=42308 (0) - ntt-1 dmx-2147483648 -1 Preparing
23:29:25.110 - ARCP: Start tv=0 channels=1 startTime=0.000
23:29:25.142 - 
23:29:25.142 - [0]########################### Mark of finish channel changing. (1.813ms) #############
23:29:25.143 - 
23:29:25.144 - ------sGetVideoParams
23:29:25.747 - * s:100 q:100 tr=50842 (0) - ntt0 dmx1175 -1 Playing
23:29:26.785 - * s:100 q:100 tr=8180 (0) - ntt0 dmx104 -1 Playing
23:29:27.762 - * s:0 q:100 tr=0 (0) - ntt860 dmx0 -1 Buffering
23:29:28.308 - 
23:29:28.308 - 
23:29:28.309 - ########################### Mark of start channel changing.###########################
23:29:28.309 - 
23:29:28.309 - GraphManager::MainChangeChannel MSNBCD HD  1 provs
23:29:28.309 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:29:28.309 - SetChannelLogic::SetChannel 0
23:29:28.310 - SetChannelLogic::SetNormalChannel
23:29:28.310 - [101]Stop channel
23:29:28.310 - PreScanner.Stop
23:29:28.311 - GraphState: Undef by PreScanner.Stop
23:29:28.311 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
23:29:28.311 - Prereader::ReaderCursor.. OK
23:29:28.311 - ReaderCursor::SetPosition 0 (w=537068480495616)
23:29:28.311 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
23:29:28.312 - Prereader.StartPlayback after lock
23:29:28.312 - subsm.StopChannel
23:29:28.312 - DestroyPlayer th=0
23:29:28.313 - ARCP: Stop
23:29:28.349 - ARCP: Stop
23:29:28.349 - DestroyPlayer - after
23:29:28.349 - **** StopChannel time 0.031ms
23:29:28.349 - 
23:29:28.350 - TransponderManager::SetTransponder (CH) 0 MSNBCD HD
23:29:28.350 - switching in SetTransponder time
23:29:28.350 - --SwitchEngineTransport ITV > TS
23:29:28.350 - PreScanner.Stop
23:29:28.350 - GraphState: Undef by PreScanner.Stop
23:29:28.350 - Incoming Stream Cache -> 0
23:29:28.351 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=1
23:29:28.351 - Normal TsInputTrafficProcessor.
23:29:28.351 - FilterManager::InitOnNewDevice 1
23:29:28.352 - TS router+MarkerFinder -> InputTrafficProcessor
23:29:28.352 - Incoming Stream Cache -> TS router+MarkerFinder
23:29:28.352 - InputTrafficProcessor -> Router to timeshift
23:29:28.352 - InputTrafficProcessor(FM) -> FilterManager
23:29:28.353 - ModuleList::Free
23:29:28.353 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module - 10000000
23:29:28.353 - Api2BaseModule::GetCurrentDeviceInfo
23:29:28.354 - ModuleList::Load C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module OK
23:29:28.354 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
23:29:28.354 - TsInputTrafficProcessor.OpenTransponder 1
23:29:28.355 - FilterManager::OnSetTransponder SetChannel
23:29:28.355 - TimeShift.Reset
23:29:28.355 - TimeShift.Cursor reset
23:29:28.355 - ReaderCursor::Reset.. OK
23:29:28.356 - Prereader.Reset ...-OK
23:29:28.356 - Create EPGparser on SetChannel
23:29:28.357 - DvbEpgParser::SetChannel ID=4a000002.322093206d6b92d3.1
23:29:28.357 - DvbEpgParser::mode normal pos=0
23:29:28.358 - ModuleList::ChangeChannel 0
23:29:28.358 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:28.358 - SCH 0
23:29:28.358 - TCS set 0
23:29:28.358 - TCS set 1
23:29:28.359 - TCS set 2
23:29:28.359 - TCS set 3
23:29:28.360 - TCS set 4
23:29:28.360 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:28.360 - TCS set 5
23:29:28.360 - TCS set 6
23:29:28.360 - TCS set 7
23:29:28.361 - TransponderManager::SelectChannel
23:29:28.361 - TsInputTrafficProcessor.SelectChannel 0
23:29:28.361 - Api2BaseModule.UpdateAllOpenedChannels 0
23:29:28.361 - TCS set 8
23:29:28.361 - TsInputTrafficProcessor.UpdateChannel 0
23:29:28.362 - TCS set 9
23:29:28.362 - SCH 1
23:29:28.362 - GraphState: Undef by StartChannelIsReady
23:29:28.362 - SCH 2
23:29:28.362 - SCH 3
23:29:28.362 - PreScanner.StartPmtParserIfNeed
23:29:28.363 - PreScanner.OnTransportMarker Radio packets
23:29:28.363 - PreScanner.OnTransportMarker Skiped! same marker
23:29:28.364 - --SwitchEngineTransport end
23:29:28.364 - *Transport TS
23:29:28.364 - Api2Device::SetChannel http://41.205.93.154/MSNBC/index.m3u8
23:29:28.367 - Api2Device::SetChannel result OK
23:29:28.367 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:28.367 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
23:29:28.368 - TsInputTrafficProcessor.OpenTransponder 1
23:29:28.368 - FilterManager::OnSetTransponder SetChannel
23:29:28.368 - TimeShift.Reset
23:29:28.368 - TimeShift.Cursor reset
23:29:28.369 - ReaderCursor::Reset.. OK
23:29:28.369 - Prereader.Reset ...-OK
23:29:28.369 - Create EPGparser on SetChannel
23:29:28.370 - DvbEpgParser::SetChannel ID=4a000002.322093206d6b92d3.1
23:29:28.371 - DvbEpgParser::mode normal pos=0
23:29:28.371 - ModuleList::ChangeChannel 0
23:29:28.371 - **** SetTransponder time 0.031ms
23:29:28.372 - [101]ChannelGraph5::StartChannel MSNBCD HD
23:29:28.372 - [101]AV:UpdateAudioParamsAfterActive
23:29:28.372 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:28.372 - GR:UpdateAudioProcessor old=0 new=0
23:29:28.372 - CPCG SetVolume 00000000 0
23:29:28.372 - 
23:29:28.373 - [101]       #################### Start stream. (0.062ms) #######################
23:29:28.373 - 
23:29:28.373 - SCH 0
23:29:28.373 - TCS set 0
23:29:28.373 - TCS set 1
23:29:28.376 - TCS set 2
23:29:28.376 - TCS set 3
23:29:28.376 - TCS set 4
23:29:28.377 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:28.377 - TimeShift.Cursor SetChannel::v 0 (0)
23:29:28.377 - ReaderCursor::SetChannel.. OK
23:29:28.377 - ReaderCursor::SetPosition 0 (w=0)
23:29:28.377 - Prereader.SetChannel..
23:29:28.378 - PMT convertor
23:29:28.378 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:28.378 - PPC.by mask. onlyV1A=0 No change PMT ,0,211,221,4095
23:29:28.378 - TCS set 5
23:29:28.378 - TCS set 6
23:29:28.379 - Plugin::SetChannel 1
23:29:28.379 - TCS set 7
23:29:28.379 - TransponderManager::SelectChannel
23:29:28.379 - TsInputTrafficProcessor.SelectChannel 1
23:29:28.379 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:28.379 - TCS set 8
23:29:28.380 - TsInputTrafficProcessor.UpdateChannel 1
23:29:28.380 - TCS set 9
23:29:28.380 - SCH 1
23:29:28.380 - GraphState: Undef by StartChannelIsReady
23:29:28.380 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:28.381 - VideoMediaTypeDetection: h.264
23:29:28.381 - SetMarkerAudioLngStream Gen New stream
23:29:28.381 - SimpleTsDemux::Init apid=221, vpid=211
23:29:28.381 - GraphState: RequestPATScan by StartChannelIsReady TS
23:29:28.381 - Api2BaseModule.Filters_EnabledFullTS 1
23:29:28.382 - PlayTimePATParser.OpenParser 1
23:29:28.382 - Open TSTS filter pid=0 data=0
23:29:28.382 - TSTS cur size=0
23:29:28.382 - SCH 2
23:29:28.383 - **** StartChannel time 0.016ms
23:29:28.383 - [101]OnChangeCurrentGraph2 -endOfSetChannel
23:29:28.383 - [101] ChannelGraph.SetActiveState 1 (old=1)
23:29:28.545 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:28.614 - GraphManager::SelectGraph 101
23:29:28.615 - [101]**** SetChannel is finished (OK). Duration 0.296ms
23:29:28.726 - Api2BaseModule.OnTunerStateChanged Playing
23:29:28.729 - Add timer 1000 HlsReader
23:29:28.729 - Api2BaseModule.EngineApi_ChList_SendSubChannels 0/4
23:29:28.771 - * s:0 q:0 tr=0 (0) - ntt-1 dmx0 -1 Connecting
23:29:29.775 - TransportMarker: TS
23:29:29.776 - * s:100 q:100 tr=188 (0) - ntt-1 dmx0 -1 Preparing
23:29:29.776 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:29.776 - 
23:29:29.776 -        #################### First traffic. (1.468ms) #######################
23:29:29.777 - 
23:29:29.777 - PlayTimePATParser.EndTask
23:29:29.942 - --SwitchEngineTransport TS > TS
23:29:29.943 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:29.943 - PreScanner.OnTransportMarker TS
23:29:29.944 - --SwitchEngineTransport end
23:29:29.945 - PreScanner.OnAfterScanPat count=2 {1;4095}
23:29:29.945 - Api2BaseModule.Filters_EnabledFullTS 0
23:29:29.945 - GraphState: RequestPMTScan by OnAfterScanPat
23:29:29.946 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
23:29:29.946 - Open TSTS filter pid=4095 data=2
23:29:29.946 - TSTS cur size=65048
23:29:29.946 - TSTS quick finish
23:29:29.947 - PreScanner.OnAfterScanPmt pmt=4095
23:29:29.947 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
23:29:29.947 - PlayTimePmtParser::StopParser
23:29:29.948 - SCH 0
23:29:29.948 - TCS set 0
23:29:29.948 - TCS set 1
23:29:29.952 - TCS set 2
23:29:29.953 - TCS set 3
23:29:29.953 - TCS set 4
23:29:29.953 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=0
23:29:29.953 - TimeShift.Cursor SetChannel::v 139496 (139496)
23:29:29.953 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:29.954 - PPC.by mask. onlyV1A=0 No change PMT ,0,211,221,4095
23:29:29.954 - TCS set 5
23:29:29.954 - TCS set 6
23:29:29.954 - Plugin::SetChannel 1
23:29:29.955 - TCS set 7
23:29:29.955 - TransponderManager::SelectChannel
23:29:29.955 - TsInputTrafficProcessor.SelectChannel 1
23:29:29.955 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:29.956 - TCS set 8
23:29:29.956 - TsInputTrafficProcessor.UpdateChannel 1
23:29:29.956 - TCS set 9
23:29:29.956 - SCH 1
23:29:29.957 - PreScanner.StartChannelIsReady net=1 parseall=0
23:29:29.957 - SCH 2
23:29:29.957 - SCH 3
23:29:29.957 - LngDefaultSelector.try select audio pid=2510 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:29.957 - LngDefaultSelector.try select audio pid=57729 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:29.958 - LngDefaultSelector.try select audio pid=19514 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:29.958 - ChannelGraph::AfterDetectChannelParams
23:29:29.958 - VideoMediaTypeDetection: h.264
23:29:29.958 - SimpleTsDemux::Init apid=221, vpid=211
23:29:29.958 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:29:29.959 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
23:29:29.959 - Prereader::ReaderCursor.. OK
23:29:29.959 - ReaderCursor::SetPosition 0 (w=599130757922816)
23:29:29.960 - Prereader.StartPlayback Push buffering=0 recv=0C80CD9C [StartChannelHelperBase(sl)]
23:29:29.960 - Prereader.StartPlayback after lock
23:29:29.960 - PreScanner.StartPmtParserIfNeed
23:29:29.960 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
23:29:29.960 - Open TSTS filter pid=4095 data=2
23:29:29.961 - TSTS cur size=139496
23:29:29.975 - VideoParser::First packet 65783 h.264
23:29:29.975 - h264 detected 0 ms
23:29:29.975 - AudioParser::First packet 743
23:29:29.976 - AudioParser::aac detected
23:29:29.976 - PreScanner set audio type 0->9 pid=221
23:29:29.976 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:29:29.976 - PreScanner.Post_InitRender [ReceiveTraffic]
23:29:29.977 - 
23:29:29.977 - [101]       #################### Init Render. (1.671ms) #######################
23:29:29.977 - 
23:29:29.978 - PlaybackTag=DS
23:29:29.978 - Channel PlaybackWay after check DS
23:29:29.978 - GraphState: GraphStarted by AsyncInitRender
23:29:29.979 - CalculBufferingSize prov=0C71234C marker=1 request=1
23:29:29.979 - CalculBuffering 800ms br=2000kb to 200 kbytes. By type
23:29:29.979 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:29:29.979 - Prereader::ReaderCursor.. OK
23:29:29.980 - ReaderCursor::SetPosition 0 (w=599130757922816)
23:29:29.980 - Prereader.StartPlayback Pull buffering=204800 recv=00000000 [EnablePullModeAndStartPlayback]
23:29:29.980 - Prereader.StartPlayback after lock
23:29:29.981 - Buffering/prereader size 200/976 kbytes
23:29:29.981 - Not same player! old 0 new 13 prev 3
23:29:29.981 - ------sGetVideoParams
23:29:29.982 - Add timer 1000 DirectShowPlayer
23:29:29.982 - [101]CreateVideoWindow GetPosition 1479x934
23:29:29.983 - [101]Graph::SetVideoWindowRect {272 0 1206 934}
23:29:29.985 - foreground
23:29:29.985 - Graph::UpdateRenderMode
23:29:29.986 -     CreateVideoWindow
23:29:29.986 - Graph::SetVideoRect {8 8 712 568}
23:29:29.986 - [101]Graph::SetVideoWindowRect {117 0 1362 934}
23:29:29.986 - demux: ProgDemux
23:29:29.991 - ------sGetVideoParams
23:29:29.992 - ChannelGraph5.CreateInterfacesAndRenderGraph
23:29:30.003 - CreateCustomGraph
23:29:30.003 - VideoType:H.264/AVC ffmpeg=0
23:29:30.003 - Video/4.H264 : MPEG2_VIDEO s={0,0,960,540}, t={0,0,960,540}, bmp=960x544, r=16/9, br=0,frametime=33ms, IF=0x0, CF=0x0)
23:29:30.003 - Add video codec 212690fb-83e5-4526-8fd7-74478b7939cd
23:29:30.163 - * s:100 q:100 tr=209808 (0) - ntt-1 dmx0 -1 Preparing
23:29:30.205 - OK
23:29:30.205 - CChannelGraph5::CreateRender 6
23:29:30.205 - VR: create new render
23:29:30.673 - * s:100 q:100 tr=255492 (0) - ntt-1 dmx0 -1 Preparing
23:29:31.188 - VR: Add video renderer Video Renderer - OK
23:29:31.188 - CreateAudioPartOfGraph
23:29:31.189 - AudioType:aac/adts 
23:29:31.189 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 48000 16bit formatTag= 0x1600)
23:29:31.194 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
23:29:31.212 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:FxSound Speakers (FxSound Audio Enhancer) OK (normal)
23:29:31.213 - Added audio renderer
23:29:31.213 - AP: created
23:29:31.213 - Added audio processor
23:29:31.217 - Connect codec to AP - OK
23:29:31.225 - Add and connect audio renderer - OK
23:29:31.225 - Graph::AfterRender
23:29:31.227 - * s:100 q:100 tr=262824 (0) - ntt-1 dmx0 -1 Preparing
23:29:31.230 - [101]AV:UpdateAudioParamsAfterActive
23:29:31.230 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:31.231 - GR:UpdateAudioProcessor old=1 new=0
23:29:31.231 - Destroy Audio Processor update
23:29:31.231 - CPCG SetVolume 00803548 0
23:29:31.231 - Graph::SetVolume 0% -10000db
23:29:31.232 - Graph::Run
23:29:31.293 - Graph::Run 1
23:29:31.293 - Graph::SetVideoRect {12 8 948 536}
23:29:31.300 - [101]Graph::SetVideoWindowRect {0 51 1479 882}
23:29:31.305 - Graph::SetPictureParams 1
23:29:31.306 - [101]AV:UpdateAudioParamsAfterActive
23:29:31.306 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:31.306 - GR:UpdateAudioProcessor old=0 new=0
23:29:31.307 - CPCG SetVolume 00803548 0
23:29:31.307 - Graph::SetVolume 0% -10000db
23:29:31.307 - Graph::Run
23:29:31.307 - Graph::Run 0
23:29:31.307 - subsm.StartChannel demux=ProgDemux tr=TS
23:29:31.308 - subsm.RecreateSubtitlesParsers 0C6116F8 ts=1 subindex=3 st=3
23:29:31.308 - subsm.Stop teletext parser
23:29:31.308 - subsm.Set CC parser
23:29:31.308 - SS_CC: SetChannel ts2pes
23:29:31.309 - SS_CC: PES2TS pid
23:29:31.309 - subsm.RecreateSubtitlesParsers end
23:29:31.309 - subs: OnSetChannel
23:29:31.309 - Add timer 100 Subtitles
23:29:31.310 - ------sGetVideoParams
23:29:31.313 - subsm.StartChannel demux=ProgDemux tr=TS
23:29:31.313 - subsm.RecreateSubtitlesParsers 0C6116F8 ts=1 subindex=3 st=3
23:29:31.313 - subsm.Stop teletext parser
23:29:31.314 - SS_CC: SetChannel ts2pes
23:29:31.314 - SS_CC: PES2TS pid
23:29:31.314 - subsm.RecreateSubtitlesParsers end
23:29:31.314 - subs: OnSetChannel
23:29:31.363 - 
23:29:31.363 - [0]########################### Mark of finish channel changing. (3.046ms) #############
23:29:31.363 - 
23:29:31.479 - Graph::OnVideoWindowPositionChanged
23:29:31.792 - * s:50 q:100 tr=105280 (0) - ntt78 dmx4672 -1 Buffering
23:29:32.794 - * s:100 q:100 tr=339528 (0) - ntt16 dmx5952 -1 Playing
23:29:33.662 - 
23:29:33.662 - 
23:29:33.662 - ########################### Mark of start channel changing.###########################
23:29:33.662 - 
23:29:33.662 - GraphManager::MainChangeChannel LBC News  1 provs
23:29:33.663 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:29:33.663 - SetChannelLogic::SetChannel 0
23:29:33.663 - SetChannelLogic::SetNormalChannel
23:29:33.664 - [101]Stop channel
23:29:33.664 - PreScanner.Stop
23:29:33.664 - GraphState: Undef by PreScanner.Stop
23:29:33.665 - PlayTimePmtParser::StopParser
23:29:33.665 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
23:29:33.665 - Prereader::ReaderCursor.. OK
23:29:33.665 - ReaderCursor::SetPosition 0 (w=6717208591859712)
23:29:33.666 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
23:29:33.666 - Prereader.StartPlayback after lock
23:29:33.666 - subsm.StopChannel
23:29:33.666 - SS_CC: SetChannel ts2pes
23:29:33.667 - DestroyPlayer th=0
23:29:33.667 - Graph::Stop
23:29:33.680 - Before Stop ...23:29:33.681 - OnGraphStop ...23:29:33.689 - VR: Disconnect
23:29:33.690 - Graph::ReleaseAllFilters
23:29:33.691 - release: Prog Audio renderer - RR - OK.
23:29:33.695 - release: Video Renderer - RR - OK.
23:29:33.736 - release: Microsoft DTV-DVD Video Decoder - RR - OK.
23:29:33.770 - release: Audio Processor - RR - OK.
23:29:33.771 - release: Microsoft DTV-DVD Audio Decoder - RR - OK.
23:29:33.772 - FilterGraph: 1
23:29:33.774 - Delete timer 1000 DirectShowPlayer
23:29:33.774 - Graph::ReleaseAllFilters
23:29:33.779 - DestroyPlayer - after
23:29:33.780 - **** StopChannel time 0.109ms
23:29:33.780 - 
23:29:33.780 - TransponderManager::SetTransponder (CH) 0 LBC News
23:29:33.781 - *Transport TS
23:29:33.781 - Api2Device::SetChannel http://icecast.thisisdax.com/LBCNewsLondonMP3.m3u
23:29:33.782 - Delete timer 1000 HlsReader
23:29:33.784 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:33.789 - Api2Device::SetChannel result OK
23:29:33.789 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:29:33.789 - TsInputTrafficProcessor.OpenTransponder 1
23:29:33.789 - FilterManager::OnSetTransponder SetChannel
23:29:33.790 - TimeShift.Reset
23:29:33.790 - TimeShift.Cursor reset
23:29:33.790 - ReaderCursor::Reset.. OK
23:29:33.791 - Prereader.Reset ...-OK
23:29:33.791 - Create EPGparser on SetChannel
23:29:33.791 - DvbEpgParser::SetChannel ID=4a000002.376bdf855581d8f9.abcd
23:29:33.792 - DvbEpgParser::mode normal pos=0
23:29:33.793 - ModuleList::ChangeChannel 0
23:29:33.793 - **** SetTransponder time 0.016ms
23:29:33.794 - [101]ChannelGraph5::StartChannel LBC News
23:29:33.794 - [101]AV:UpdateAudioParamsAfterActive
23:29:33.794 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:33.794 - GR:UpdateAudioProcessor old=0 new=0
23:29:33.795 - CPCG SetVolume 00000000 0
23:29:33.795 - 
23:29:33.795 - [101]       #################### Start stream. (0.125ms) #######################
23:29:33.795 - 
23:29:33.795 - SCH 0
23:29:33.796 - TCS set 0
23:29:33.796 - TCS set 1
23:29:33.799 - TCS set 2
23:29:33.799 - TCS set 3
23:29:33.800 - TCS set 4
23:29:33.800 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:33.800 - TimeShift.Cursor SetChannel::v 0 (0)
23:29:33.800 - ReaderCursor::SetChannel.. OK
23:29:33.801 - ReaderCursor::SetPosition 0 (w=0)
23:29:33.801 - Prereader.SetChannel..
23:29:33.801 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:33.801 - PPC.by mask. onlyV1A=0 No change PMT ,0
23:29:33.802 - TCS set 5
23:29:33.802 - TCS set 6
23:29:33.802 - Plugin::SetChannel 43981
23:29:33.802 - TCS set 7
23:29:33.802 - TransponderManager::SelectChannel
23:29:33.803 - TsInputTrafficProcessor.SelectChannel 43981
23:29:33.803 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:33.803 - TCS set 8
23:29:33.804 - TsInputTrafficProcessor.UpdateChannel 43981
23:29:33.805 - TCS set 9
23:29:33.806 - SCH 1
23:29:33.806 - GraphState: Undef by StartChannelIsReady
23:29:33.806 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:33.807 - SimpleTsDemux::Init apid=0, vpid=0
23:29:33.807 - GraphState: RequestPATScan by StartChannelIsReady TS
23:29:33.807 - Api2BaseModule.Filters_EnabledFullTS 1
23:29:33.808 - PlayTimePATParser.OpenParser 43981
23:29:33.808 - Open TSTS filter pid=0 data=0
23:29:33.808 - TSTS cur size=0
23:29:33.808 - SCH 2
23:29:33.808 - **** StartChannel time 0.016ms
23:29:33.809 - [101]OnChangeCurrentGraph2 -endOfSetChannel
23:29:33.809 - [101] ChannelGraph.SetActiveState 1 (old=1)
23:29:33.817 - ------sGetVideoParams
23:29:33.850 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:33.891 - GraphManager::SelectGraph 101
23:29:33.892 - [101]**** SetChannel is finished (OK). Duration 0.219ms
23:29:33.913 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:33.913 - Api2BaseModule.OnTunerStateChanged Playing
23:29:33.918 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:33.927 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:33.981 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:34.048 - Api2BaseModule.OnTunerStateChanged Playing
23:29:34.049 - TsInputTrafficProcessor - switch to AV mode
23:29:34.050 - 
23:29:34.050 -        #################### First traffic. (0.375ms) #######################
23:29:34.050 - 
23:29:34.050 - TransportMarker: Radio packets
23:29:34.051 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:34.073 - --SwitchEngineTransport TS > ITV
23:29:34.073 - PreScanner.Stop
23:29:34.073 - GraphState: Undef by PreScanner.Stop
23:29:34.073 - PlayTimePmtParser::StopParser
23:29:34.074 - Api2BaseModule.Filters_EnabledFullTS 0
23:29:34.074 - Incoming Stream Cache -> 0
23:29:34.074 - ModuleList::Free
23:29:34.075 - TrafficProcessingScheme_CreateForNewEngineTransport 2 ss=2
23:29:34.075 - InputTrafficHelper -> ITV2Timeshift
23:29:34.076 - TS router+MarkerFinder -> InputTrafficHelper
23:29:34.076 - Send marker again
23:29:34.076 - Incoming Stream Cache -> InputTrafficHelper
23:29:34.077 - ReindexForItvAfterTransport -> TimeShift reindex
23:29:34.077 - TimeShift.Cursor reset
23:29:34.077 - ReaderCursor::Reset.. OK
23:29:34.077 - Prereader.Reset ...-OK
23:29:34.098 - Prereader.Exit23:29:34.100 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:29:34.100 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:34.100 - SCH 0
23:29:34.100 - TCS set 0
23:29:34.100 - TCS set 1
23:29:34.101 - TCS set 2
23:29:34.101 - TCS set 3
23:29:34.101 - TCS set 4
23:29:34.101 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
23:29:34.101 - TimeShift.Cursor SetChannel::v 0 (27720)
23:29:34.101 - ReaderCursor::SetChannel.. OK
23:29:34.102 - ReaderCursor::SetPosition 0 (w=119056493445120)
23:29:34.102 - Prereader.SetChannel..
23:29:34.102 - Prereader.SetChannel unmask 1
23:29:34.102 - TCS set 5
23:29:34.103 - TCS set 6
23:29:34.103 - Plugin::SetChannel 43981
23:29:34.103 - TCS set 7
23:29:34.103 - TransponderManager::SelectChannel
23:29:34.103 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:34.103 - TCS set 8
23:29:34.104 - TCS set 9
23:29:34.104 - SCH 1
23:29:34.104 - GraphState: Undef by StartChannelIsReady
23:29:34.104 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:34.104 - GraphState: RequestTransponderMark by StartChannelIsReady ITV
23:29:34.105 - SCH 2
23:29:34.105 - PreScanner.OnTransportMarker Radio packets
23:29:34.105 - EngineTransportsParser -> EngineTransportsParserRouter
23:29:34.106 - !marker: audio {mp3-parsing} video {--none} items( MediaType:0:len=0 )
23:29:34.106 - GraphState: RequestMediaTypeDetection by OnTransportMarker Not ready
23:29:34.106 - SCH 0
23:29:34.106 - TCS set 0
23:29:34.107 - TCS set 1
23:29:34.107 - TCS set 2
23:29:34.107 - CacheBuffer.Suspended
23:29:34.107 - TCS set 3
23:29:34.108 - TCS set 4
23:29:34.108 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
23:29:34.108 - TimeShift.Cursor SetChannel::v 27720 (27720)
23:29:34.108 - Prereader.SetChannel unmask 1
23:29:34.109 - TCS set 5
23:29:34.109 - TCS set 6
23:29:34.109 - Plugin::SetChannel 43981
23:29:34.109 - TCS set 7
23:29:34.109 - TransponderManager::SelectChannel
23:29:34.110 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:34.110 - TCS set 8
23:29:34.110 - TCS set 9
23:29:34.110 - SCH 1
23:29:34.110 - PreScanner.StartChannelIsReady net=1 parseall=0
23:29:34.111 - SCH 2
23:29:34.111 - SCH 3
23:29:34.111 - LngDefaultSelector.try select audio pid=0 type=1 lng=0-(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:34.111 - ChannelGraph::AfterDetectChannelParams
23:29:34.111 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:29:34.112 - TimeShift.Cursor PushMode 1 1 (pos=27) [StartChannelHelperBase(sl)]
23:29:34.112 - Prereader::ReaderCursor.. OK
23:29:34.112 - ReaderCursor::SetPosition 27720 (w=119056493445120)
23:29:34.112 - Prereader.StartPlayback Push buffering=0 recv=0C80CD9C [StartChannelHelperBase(sl)]
23:29:34.112 - Prereader.StartPlayback after lock
23:29:34.113 - CacheBuffer.Suspended
23:29:34.113 - PreScanner.StartPmtParserIfNeed
23:29:34.113 - PlayTimePmtParser::StopParser
23:29:34.114 - --SwitchEngineTransport end
23:29:34.129 - AudioParser::First packet 2000
23:29:34.129 - PreScanner.OnFirstItvTraffic
23:29:34.129 - AudioParser::MP3 detected
23:29:34.130 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:29:34.130 - PreScanner.Post_InitRender [ReceiveTraffic]
23:29:34.130 - 
23:29:34.130 - [101]       #################### Init Render. (0.453ms) #######################
23:29:34.131 - 
23:29:34.131 - selector PlaybackTag=none videocodec=FFmpegS/ ts=0
23:29:34.131 - Radio
23:29:34.131 - Channel PlaybackWay after check FFmpegS
23:29:34.132 - GraphState: GraphStarted by AsyncInitRender
23:29:34.132 - CalculBufferingSize prov=0C71234C marker=1 request=1
23:29:34.132 - CalculBuffering 800ms br=48kb to 4 kbytes. from marker
23:29:34.132 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:29:34.133 - Prereader::ReaderCursor.. OK
23:29:34.133 - ReaderCursor::SetPosition 0 (w=244503898226688)
23:29:34.133 - Prereader.StartPlayback Pull buffering=4915 recv=00000000 [EnablePullModeAndStartPlayback]
23:29:34.133 - Prereader.StartPlayback after lock
23:29:34.134 - Buffering/prereader size 4/976 kbytes
23:29:34.134 - Not same player! old 0 new 3 prev 13
23:29:34.134 - call recreate of surface
23:29:34.135 - ------sGetVideoParams
23:29:34.135 - Create FFMpegTvPlayer
23:29:34.137 - ITV demux -> ItvCorrectionDemux
23:29:34.137 - before render create or reset m=3 radio=1
23:29:34.137 - [101]AV:UpdateAudioParamsAfterActive
23:29:34.137 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:34.138 - GR:UpdateAudioProcessor old=0 new=0
23:29:34.138 - CPCG SetVolume 0DF8E410 0
23:29:34.138 - subsm.StartChannel demux=FFmpeg tr=AVPackets
23:29:34.138 - subsm.RecreateSubtitlesParsers 0DF8E428 ts=0 subindex=-1 st=-1
23:29:34.139 - subsm.RecreateSubtitlesParsers end
23:29:34.140 - subs: OnSetChannel
23:29:34.140 - Delete timer 100 Subtitles
23:29:34.141 - ------sGetVideoParams
23:29:34.178 - * s:100 q:100 tr=48085 (0) - ntt0 dmx-2147483648 -1 Preparing
23:29:34.257 - ARCP: Start tv=0 channels=1 startTime=0.000
23:29:34.304 - 
23:29:34.304 - [0]########################### Mark of finish channel changing. (0.641ms) #############
23:29:34.304 - 
23:29:34.306 - ------sGetVideoParams
23:29:34.809 - * s:48 q:100 tr=45065 (0) - ntt0 dmx1227 -1 Buffering
23:29:35.810 - * s:100 q:100 tr=8180 (0) - ntt0 dmx235 -1 Playing
23:29:36.817 - * s:0 q:100 tr=0 (0) - ntt0 dmx0 -1 Buffering
23:29:37.815 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:29:38.823 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:29:39.075 - 
23:29:39.076 - 
23:29:39.076 - ########################### Mark of start channel changing.###########################
23:29:39.076 - 
23:29:39.076 - GraphManager::MainChangeChannel MSNBCD HD  1 provs
23:29:39.076 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:29:39.077 - SetChannelLogic::SetChannel 0
23:29:39.077 - SetChannelLogic::SetNormalChannel
23:29:39.077 - [101]Stop channel
23:29:39.078 - PreScanner.Stop
23:29:39.078 - GraphState: Undef by PreScanner.Stop
23:29:39.078 - PlayTimePmtParser::StopParser
23:29:39.078 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
23:29:39.078 - Prereader::ReaderCursor.. OK
23:29:39.079 - ReaderCursor::SetPosition 0 (w=572390291537920)
23:29:39.079 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
23:29:39.079 - Prereader.StartPlayback after lock
23:29:39.079 - subsm.StopChannel
23:29:39.080 - SS_CC: SetChannel ts2pes
23:29:39.080 - DestroyPlayer th=0
23:29:39.080 - ARCP: Stop
23:29:39.125 - ARCP: Stop
23:29:39.125 - DestroyPlayer - after
23:29:39.125 - **** StopChannel time 0.047ms
23:29:39.126 - 
23:29:39.126 - TransponderManager::SetTransponder (CH) 0 MSNBCD HD
23:29:39.126 - switching in SetTransponder time
23:29:39.126 - --SwitchEngineTransport ITV > TS
23:29:39.126 - PreScanner.Stop
23:29:39.126 - GraphState: Undef by PreScanner.Stop
23:29:39.127 - PlayTimePmtParser::StopParser
23:29:39.127 - Incoming Stream Cache -> 0
23:29:39.127 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=1
23:29:39.127 - Normal TsInputTrafficProcessor.
23:29:39.128 - FilterManager::InitOnNewDevice 1
23:29:39.128 - TS router+MarkerFinder -> InputTrafficProcessor
23:29:39.128 - Incoming Stream Cache -> TS router+MarkerFinder
23:29:39.129 - InputTrafficProcessor -> Router to timeshift
23:29:39.129 - InputTrafficProcessor(FM) -> FilterManager
23:29:39.129 - ModuleList::Free
23:29:39.129 - LoadDll C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module - 10000000
23:29:39.130 - Api2BaseModule::GetCurrentDeviceInfo
23:29:39.130 - ModuleList::Load C:\Program Files (x86)\ProgDVB Professional\Modules\PidRecorder.module OK
23:29:39.130 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
23:29:39.130 - TsInputTrafficProcessor.OpenTransponder 1
23:29:39.131 - FilterManager::OnSetTransponder SetChannel
23:29:39.131 - TimeShift.Reset
23:29:39.131 - TimeShift.Cursor reset
23:29:39.131 - ReaderCursor::Reset.. OK
23:29:39.132 - Prereader.Reset ...-OK
23:29:39.132 - Create EPGparser on SetChannel
23:29:39.132 - DvbEpgParser::SetChannel ID=4a000002.322093206d6b92d3.1
23:29:39.133 - DvbEpgParser::mode normal pos=0
23:29:39.133 - ModuleList::ChangeChannel 0
23:29:39.134 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:39.134 - SCH 0
23:29:39.134 - TCS set 0
23:29:39.134 - TCS set 1
23:29:39.135 - TCS set 2
23:29:39.135 - TCS set 3
23:29:39.136 - TCS set 4
23:29:39.136 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:39.136 - TCS set 5
23:29:39.136 - TCS set 6
23:29:39.137 - TCS set 7
23:29:39.137 - TransponderManager::SelectChannel
23:29:39.137 - TsInputTrafficProcessor.SelectChannel 0
23:29:39.137 - Api2BaseModule.UpdateAllOpenedChannels 0
23:29:39.137 - TCS set 8
23:29:39.138 - TsInputTrafficProcessor.UpdateChannel 0
23:29:39.138 - TCS set 9
23:29:39.138 - SCH 1
23:29:39.138 - GraphState: Undef by StartChannelIsReady
23:29:39.138 - SCH 2
23:29:39.138 - SCH 3
23:29:39.139 - PreScanner.StartPmtParserIfNeed
23:29:39.139 - PlayTimePmtParser::StopParser
23:29:39.139 - PreScanner.OnTransportMarker Radio packets
23:29:39.139 - PreScanner.OnTransportMarker Skiped! same marker
23:29:39.140 - --SwitchEngineTransport end
23:29:39.140 - *Transport TS
23:29:39.141 - Api2Device::SetChannel http://41.205.93.154/MSNBC/index.m3u8
23:29:39.143 - Api2Device::SetChannel result OK
23:29:39.143 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:39.143 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=1
23:29:39.144 - TsInputTrafficProcessor.OpenTransponder 1
23:29:39.144 - FilterManager::OnSetTransponder SetChannel
23:29:39.144 - TimeShift.Reset
23:29:39.145 - TimeShift.Cursor reset
23:29:39.145 - ReaderCursor::Reset.. OK
23:29:39.145 - Prereader.Reset ...-OK
23:29:39.146 - Create EPGparser on SetChannel
23:29:39.146 - DvbEpgParser::SetChannel ID=4a000002.322093206d6b92d3.1
23:29:39.147 - DvbEpgParser::mode normal pos=0
23:29:39.147 - ModuleList::ChangeChannel 0
23:29:39.148 - **** SetTransponder time 0.031ms
23:29:39.148 - [101]ChannelGraph5::StartChannel MSNBCD HD
23:29:39.148 - [101]AV:UpdateAudioParamsAfterActive
23:29:39.148 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:39.148 - GR:UpdateAudioProcessor old=0 new=0
23:29:39.149 - CPCG SetVolume 00000000 0
23:29:39.149 - 
23:29:39.149 - [101]       #################### Start stream. (0.078ms) #######################
23:29:39.149 - 
23:29:39.149 - SCH 0
23:29:39.149 - TCS set 0
23:29:39.150 - TCS set 1
23:29:39.152 - TCS set 2
23:29:39.152 - TCS set 3
23:29:39.152 - TCS set 4
23:29:39.153 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:39.153 - TimeShift.Cursor SetChannel::v 0 (0)
23:29:39.153 - ReaderCursor::SetChannel.. OK
23:29:39.153 - ReaderCursor::SetPosition 0 (w=0)
23:29:39.153 - Prereader.SetChannel..
23:29:39.154 - PMT convertor
23:29:39.154 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:39.154 - PPC.by mask. onlyV1A=0 No change PMT ,0,211,221,4095
23:29:39.154 - TCS set 5
23:29:39.154 - TCS set 6
23:29:39.155 - Plugin::SetChannel 1
23:29:39.155 - TCS set 7
23:29:39.155 - TransponderManager::SelectChannel
23:29:39.155 - TsInputTrafficProcessor.SelectChannel 1
23:29:39.155 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:39.156 - TCS set 8
23:29:39.156 - TsInputTrafficProcessor.UpdateChannel 1
23:29:39.156 - TCS set 9
23:29:39.156 - SCH 1
23:29:39.157 - GraphState: Undef by StartChannelIsReady
23:29:39.157 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:39.157 - VideoMediaTypeDetection: h.264
23:29:39.157 - SetMarkerAudioLngStream Gen New stream
23:29:39.158 - SimpleTsDemux::Init apid=221, vpid=211
23:29:39.158 - GraphState: RequestPATScan by StartChannelIsReady TS
23:29:39.158 - Api2BaseModule.Filters_EnabledFullTS 1
23:29:39.158 - PlayTimePATParser.OpenParser 1
23:29:39.158 - Open TSTS filter pid=0 data=0
23:29:39.159 - TSTS cur size=0
23:29:39.159 - SCH 2
23:29:39.159 - **** StartChannel time 0.000ms
23:29:39.159 - [101]OnChangeCurrentGraph2 -endOfSetChannel
23:29:39.159 - [101] ChannelGraph.SetActiveState 1 (old=1)
23:29:39.321 - Api2BaseModule.OnTunerStateChanged Preparing
23:29:39.378 - GraphManager::SelectGraph 101
23:29:39.378 - [101]**** SetChannel is finished (OK). Duration 0.297ms
23:29:39.497 - Api2BaseModule.OnTunerStateChanged Playing
23:29:39.500 - Add timer 1000 HlsReader
23:29:39.500 - Api2BaseModule.EngineApi_ChList_SendSubChannels 0/4
23:29:39.851 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:40.554 - TransportMarker: TS
23:29:40.554 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:40.555 - 
23:29:40.555 - --SwitchEngineTransport TS > TS
23:29:40.556 -        #################### First traffic. (1.485ms) #######################
23:29:40.557 - 
23:29:40.557 - PlayTimePATParser.EndTask
23:29:40.558 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:29:40.559 - PreScanner.OnTransportMarker TS
23:29:40.562 - --SwitchEngineTransport end
23:29:40.562 - PreScanner.OnAfterScanPat count=2 {1;4095}
23:29:40.563 - Api2BaseModule.Filters_EnabledFullTS 0
23:29:40.564 - GraphState: RequestPMTScan by OnAfterScanPat
23:29:40.565 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=1
23:29:40.565 - Open TSTS filter pid=4095 data=2
23:29:40.566 - TSTS cur size=44556
23:29:40.567 - TSTS quick finish
23:29:40.567 - PreScanner.OnAfterScanPmt pmt=4095
23:29:40.569 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
23:29:40.570 - PlayTimePmtParser::StopParser
23:29:40.571 - SCH 0
23:29:40.571 - TCS set 0
23:29:40.572 - TCS set 1
23:29:40.577 - TCS set 2
23:29:40.577 - TCS set 3
23:29:40.578 - TCS set 4
23:29:40.578 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=0
23:29:40.578 - TimeShift.Cursor SetChannel::v 44556 (44556)
23:29:40.578 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:40.579 - PPC.by mask. onlyV1A=0 No change PMT ,0,211,221,4095
23:29:40.579 - TCS set 5
23:29:40.579 - TCS set 6
23:29:40.579 - Plugin::SetChannel 1
23:29:40.580 - TCS set 7
23:29:40.580 - TransponderManager::SelectChannel
23:29:40.580 - TsInputTrafficProcessor.SelectChannel 1
23:29:40.580 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:40.580 - TCS set 8
23:29:40.581 - TsInputTrafficProcessor.UpdateChannel 1
23:29:40.581 - TCS set 9
23:29:40.581 - SCH 1
23:29:40.581 - PreScanner.StartChannelIsReady net=1 parseall=0
23:29:40.581 - SCH 2
23:29:40.582 - SCH 3
23:29:40.582 - LngDefaultSelector.try select audio pid=2510 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:40.582 - LngDefaultSelector.try select audio pid=57729 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:40.582 - LngDefaultSelector.try select audio pid=19514 type=64 lng=6581877-und(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:29:40.583 - ChannelGraph::AfterDetectChannelParams
23:29:40.583 - VideoMediaTypeDetection: h.264
23:29:40.583 - SimpleTsDemux::Init apid=221, vpid=211
23:29:40.584 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:29:40.585 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
23:29:40.585 - Prereader::ReaderCursor.. OK
23:29:40.585 - ReaderCursor::SetPosition 0 (w=191366562840576)
23:29:40.586 - Prereader.StartPlayback Push buffering=0 recv=0C80CD9C [StartChannelHelperBase(sl)]
23:29:40.586 - Prereader.StartPlayback after lock
23:29:40.586 - PreScanner.StartPmtParserIfNeed
23:29:40.586 - PlayTimePmtParser::OpenParser SID=1 PMT=4095 unknow channel=0
23:29:40.587 - Open TSTS filter pid=4095 data=2
23:29:40.587 - TSTS cur size=44556
23:29:40.726 - subsm.StartChannel demux=0 tr=TS
23:29:40.727 - VideoParser::First packet 65429 h.264
23:29:40.727 - subsm.RecreateSubtitlesParsers 00000000 ts=1 subindex=3 st=3
23:29:40.728 - h264 detected 0 ms
23:29:40.729 - AudioParser::First packet 637
23:29:40.730 - AudioParser::aac detected
23:29:40.731 - PreScanner set audio type 0->9 pid=221
23:29:40.731 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:29:40.732 - PreScanner.Post_InitRender [ReceiveTraffic]
23:29:40.732 - 
23:29:40.733 - [101]       #################### Init Render. (1.656ms) #######################
23:29:40.733 - 
23:29:40.734 - PlaybackTag=DS
23:29:40.734 - Channel PlaybackWay after check DS
23:29:40.735 - GraphState: GraphStarted by AsyncInitRender
23:29:40.735 - CalculBufferingSize prov=0C71234C marker=1 request=1
23:29:40.736 - CalculBuffering 800ms br=2000kb to 200 kbytes. By type
23:29:40.736 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:29:40.737 - Prereader::ReaderCursor.. OK
23:29:40.738 - ReaderCursor::SetPosition 0 (w=524037549719552)
23:29:40.739 - Prereader.StartPlayback Pull buffering=204800 recv=00000000 [EnablePullModeAndStartPlayback]
23:29:40.739 - Prereader.StartPlayback after lock
23:29:40.740 - Buffering/prereader size 200/976 kbytes
23:29:40.740 - Not same player! old 0 new 13 prev 3
23:29:40.741 - ------sGetVideoParams
23:29:40.743 - Add timer 1000 DirectShowPlayer
23:29:40.744 - [101]CreateVideoWindow GetPosition 1479x934
23:29:40.746 - [101]Graph::SetVideoWindowRect {272 0 1206 934}
23:29:40.749 - foreground
23:29:40.749 - Graph::UpdateRenderMode
23:29:40.750 -     CreateVideoWindow
23:29:40.750 - Graph::SetVideoRect {8 8 712 568}
23:29:40.751 - [101]Graph::SetVideoWindowRect {117 0 1362 934}
23:29:40.751 - demux: ProgDemux
23:29:40.757 - ------sGetVideoParams
23:29:40.757 - ChannelGraph5.CreateInterfacesAndRenderGraph
23:29:40.761 - CreateCustomGraph
23:29:40.761 - VideoType:H.264/AVC ffmpeg=0
23:29:40.761 - Video/4.H264 : MPEG2_VIDEO s={0,0,960,540}, t={0,0,960,540}, bmp=960x544, r=16/9, br=0,frametime=33ms, IF=0x0, CF=0x0)
23:29:40.762 - Add video codec 212690fb-83e5-4526-8fd7-74478b7939cd
23:29:40.872 - OK
23:29:40.872 - CChannelGraph5::CreateRender 6
23:29:40.872 - VR: create new render
23:29:40.950 - * s:100 q:100 tr=209808 (0) - ntt0 dmx0 -1 Preparing
23:29:41.534 - * s:100 q:100 tr=262824 (0) - ntt0 dmx0 -1 Preparing
23:29:41.816 - VR: Add video renderer Video Renderer - OK
23:29:41.816 - CreateAudioPartOfGraph
23:29:41.817 - AudioType:aac/adts 
23:29:41.817 - Audio/00001600-0000-0010-8000-00aa00389b71 : WaveFormatEx (2ch 48000 16bit formatTag= 0x1600)
23:29:41.819 - Add audio codec e1f1a0b8-beee-490d-ba7c-066c40b5e2b9 - OK
23:29:41.847 - IMyGraphBuilder::CreateAudioRenderFilter::ProgAR:FxSound Speakers (FxSound Audio Enhancer) OK (normal)
23:29:41.847 - Added audio renderer
23:29:41.847 - AP: created
23:29:41.848 - Added audio processor
23:29:41.851 - Connect codec to AP - OK
23:29:41.860 - Add and connect audio renderer - OK
23:29:41.860 - Graph::AfterRender
23:29:41.865 - [101]AV:UpdateAudioParamsAfterActive
23:29:41.865 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:41.865 - GR:UpdateAudioProcessor old=1 new=0
23:29:41.866 - Destroy Audio Processor update
23:29:41.866 - CPCG SetVolume 126E67B0 0
23:29:41.866 - Graph::SetVolume 0% -10000db
23:29:41.867 - Graph::Run
23:29:41.902 - Graph::Run 1
23:29:41.902 - Graph::SetVideoRect {12 8 948 536}
23:29:41.908 - [101]Graph::SetVideoWindowRect {0 51 1479 882}
23:29:41.913 - Graph::SetPictureParams 1
23:29:41.914 - [101]AV:UpdateAudioParamsAfterActive
23:29:41.914 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:41.915 - GR:UpdateAudioProcessor old=0 new=0
23:29:41.915 - CPCG SetVolume 126E67B0 0
23:29:41.915 - Graph::SetVolume 0% -10000db
23:29:41.915 - Graph::Run
23:29:41.915 - Graph::Run 0
23:29:41.916 - subsm.StartChannel demux=ProgDemux tr=TS
23:29:41.916 - subsm.RecreateSubtitlesParsers 129CFD18 ts=1 subindex=3 st=3
23:29:41.916 - subsm.Stop teletext parser
23:29:41.916 - SS_CC: SetChannel ts2pes
23:29:41.916 - SS_CC: PES2TS pid
23:29:41.917 - subsm.RecreateSubtitlesParsers end
23:29:41.917 - subs: OnSetChannel
23:29:41.917 - Add timer 100 Subtitles
23:29:41.918 - ------sGetVideoParams
23:29:41.973 - 
23:29:41.973 - [0]########################### Mark of finish channel changing. (2.891ms) #############
23:29:41.973 - 
23:29:42.073 - * s:100 q:100 tr=251168 (0) - ntt47 dmx5057 -1 Playing
23:29:42.098 - Graph::OnVideoWindowPositionChanged
23:29:42.864 - * s:49 q:100 tr=103588 (0) - ntt125 dmx4319 -1 Buffering
23:29:43.662 - 
23:29:43.662 - 
23:29:43.662 - ########################### Mark of start channel changing.###########################
23:29:43.663 - 
23:29:43.663 - GraphManager::MainChangeChannel LBC News  1 provs
23:29:43.663 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
23:29:43.663 - SetChannelLogic::SetChannel 0
23:29:43.663 - SetChannelLogic::SetNormalChannel
23:29:43.664 - [101]Stop channel
23:29:43.664 - PreScanner.Stop
23:29:43.664 - GraphState: Undef by PreScanner.Stop
23:29:43.665 - PlayTimePmtParser::StopParser
23:29:43.665 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
23:29:43.665 - Prereader::ReaderCursor.. OK
23:29:43.666 - ReaderCursor::SetPosition 0 (w=5049008934354944)
23:29:43.666 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
23:29:43.666 - Prereader.StartPlayback after lock
23:29:43.666 - subsm.StopChannel
23:29:43.667 - SS_CC: SetChannel ts2pes
23:29:43.667 - DestroyPlayer th=0
23:29:43.667 - Graph::Stop
23:29:43.690 - Before Stop ...23:29:43.690 - OnGraphStop ...23:29:43.697 - VR: Disconnect
23:29:43.698 - Graph::ReleaseAllFilters
23:29:43.698 - release: Prog Audio renderer - RR - OK.
23:29:43.702 - release: Video Renderer - RR - OK.
23:29:43.726 - release: Microsoft DTV-DVD Video Decoder - RR - OK.
23:29:43.760 - release: Audio Processor - RR - OK.
23:29:43.760 - release: Microsoft DTV-DVD Audio Decoder - RR - OK.
23:29:43.761 - FilterGraph: 1
23:29:43.763 - Delete timer 1000 DirectShowPlayer
23:29:43.764 - Graph::ReleaseAllFilters
23:29:43.769 - DestroyPlayer - after
23:29:43.769 - **** StopChannel time 0.110ms
23:29:43.769 - 
23:29:43.769 - TransponderManager::SetTransponder (CH) 0 LBC News
23:29:43.769 - *Transport TS
23:29:43.770 - Api2Device::SetChannel http://icecast.thisisdax.com/LBCNewsLondonMP3.m3u
23:29:43.771 - Delete timer 1000 HlsReader
23:29:43.772 - Api2BaseModule.OnTunerStateChanged Connecting
23:29:43.772 - Api2Device::SetChannel result OK
23:29:43.772 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:29:43.773 - TsInputTrafficProcessor.OpenTransponder 1
23:29:43.773 - FilterManager::OnSetTransponder SetChannel
23:29:43.773 - TimeShift.Reset
23:29:43.774 - TimeShift.Cursor reset
23:29:43.774 - ReaderCursor::Reset.. OK
23:29:43.774 - Prereader.Reset ...-OK
23:29:43.775 - Create EPGparser on SetChannel
23:29:43.775 - DvbEpgParser::SetChannel ID=4a000002.376bdf855581d8f9.abcd
23:29:43.776 - DvbEpgParser::mode normal pos=0
23:29:43.776 - ModuleList::ChangeChannel 0
23:29:43.777 - **** SetTransponder time 0.015ms
23:29:43.777 - [101]ChannelGraph5::StartChannel LBC News
23:29:43.777 - [101]AV:UpdateAudioParamsAfterActive
23:29:43.777 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:29:43.777 - GR:UpdateAudioProcessor old=0 new=0
23:29:43.778 - CPCG SetVolume 00000000 0
23:29:43.778 - 
23:29:43.778 - [101]       #################### Start stream. (0.125ms) #######################
23:29:43.778 - 
23:29:43.778 - SCH 0
23:29:43.778 - TCS set 0
23:29:43.779 - TCS set 1
23:29:43.781 - TCS set 2
23:29:43.781 - TCS set 3
23:29:43.781 - TCS set 4
23:29:43.781 - TimeShift.Cursor SetChannel pidmask=0DCF9D14 first=1
23:29:43.782 - TimeShift.Cursor SetChannel::v 0 (0)
23:29:43.782 - ReaderCursor::SetChannel.. OK
23:29:43.782 - ReaderCursor::SetPosition 0 (w=0)
23:29:43.782 - Prereader.SetChannel..
23:29:43.782 - Prereader.SetChannel mask. SimplePatPmtConvertor
23:29:43.783 - PPC.by mask. onlyV1A=0 No change PMT ,0
23:29:43.783 - TCS set 5
23:29:43.783 - TCS set 6
23:29:43.783 - Plugin::SetChannel 43981
23:29:43.783 - TCS set 7
23:29:43.784 - TransponderManager::SelectChannel
23:29:43.784 - TsInputTrafficProcessor.SelectChannel 43981
23:29:43.784 - Api2BaseModule.UpdateAllOpenedChannels 1
23:29:43.784 - TCS set 8
23:29:43.784 - TsInputTrafficProcessor.UpdateChannel 43981
23:29:43.785 - TCS set 9
23:29:43.785 - SCH 1
23:29:43.785 - GraphState: Undef by StartChannelIsReady
23:29:43.785 - PreScanner.StartChannelIsReady net=1 parseall=1
23:29:43.786 - SimpleTsDemux::Init apid=0, vpid=0
23:29:43.786 - GraphState: RequestPATScan by StartChannelIsReady TS
23:29:43.786 - Api2BaseModule.Filters_EnabledFullTS 1
23:29:43.787 - PlayTimePATParser.OpenParser 43981
23:29:43.787 - Open TSTS filter pid=0 data=0
23:29:43.787 - TSTS cur size=0
23:29:43.787 - SCH 2
23:29:43.787 - **** StartChannel time 0.000ms
23:29:43.788 - [101]OnChangeCurrentGraph2 -endOfSetChannel
23:29:43.788 - [101] ChannelGraph.SetActiveState 1 (old=1)
23:29:43.794 - ------sGetVideoParams
23:29:43.874 - GraphManager::SelectGraph 101
23:29:43.875 - [101]**** SetChannel is finished (OK). Duration 0.219ms
23:29:43.938 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:44.853 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:45.866 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:46.866 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:47.880 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:48.885 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:49.908 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:50.906 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:51.914 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:52.904 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:53.922 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:54.818 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:55.809 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:56.808 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:57.913 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:58.903 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:29:59.964 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:00.978 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:01.978 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:02.984 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:03.978 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:04.990 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:05.486 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:05.989 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:06.574 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:07.245 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:07.981 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:08.770 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:09.865 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:10.859 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:11.855 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:12.960 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:13.966 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:14.997 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:16.002 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:17.001 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:18.000 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:19.007 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:19.997 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:21.010 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:21.558 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:22.380 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:23.053 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:23.657 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:24.401 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:25.069 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:25.747 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:26.424 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:27.064 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:27.942 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:28.444 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:29.073 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:29.931 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:30.458 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:31.028 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:32.083 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:33.077 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:34.079 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:35.087 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:36.115 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:37.113 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:37.633 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:38.531 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:39.113 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:39.834 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:40.541 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:41.110 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:41.926 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:42.558 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:43.114 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:44.013 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:44.581 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:45.106 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:45.270 - [101]TransponderManager::RefreshChannel ! 60s chunk=0 net=1 10000ms error0
23:30:45.271 - Do RefreshChannel
23:30:45.271 - ChannelGraph::BeforChangeCursor
23:30:45.271 - Api2Device::SetChannel http://icecast.thisisdax.com/LBCNewsLondonMP3.m3u
23:30:45.357 - Api2Device::SetChannel result OK
23:30:45.358 - CChannelGraph::AfterChangeCursor
23:30:45.358 - Api2BaseModule.OnTunerStateChanged Connecting
23:30:45.358 - CChannelGraph::AfterChangeCursor
23:30:45.427 - Api2BaseModule.OnTunerStateChanged Preparing
23:30:45.495 - Api2BaseModule.OnTunerStateChanged Playing
23:30:45.499 - Api2BaseModule.OnTunerStateChanged Connecting
23:30:45.590 - Api2BaseModule.OnTunerStateChanged Preparing
23:30:45.597 - * s:0 q:0 tr=0 (0) - ntt0 dmx0 -1 Connecting
23:30:45.657 - Api2BaseModule.OnTunerStateChanged Playing
23:30:45.660 - TsInputTrafficProcessor - switch to AV mode
23:30:45.660 - 
23:30:45.660 -        #################### First traffic. (62.000ms) #######################
23:30:45.660 - 
23:30:45.661 - TransportMarker: Radio packets
23:30:45.661 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:30:45.661 - --SwitchEngineTransport TS > ITV
23:30:45.662 - PreScanner.Stop
23:30:45.662 - GraphState: Undef by PreScanner.Stop
23:30:45.662 - PlayTimePmtParser::StopParser
23:30:45.663 - Api2BaseModule.Filters_EnabledFullTS 0
23:30:45.663 - Incoming Stream Cache -> 0
23:30:45.663 - ModuleList::Free
23:30:45.665 - TrafficProcessingScheme_CreateForNewEngineTransport 2 ss=2
23:30:45.665 - InputTrafficHelper -> ITV2Timeshift
23:30:45.666 - TS router+MarkerFinder -> InputTrafficHelper
23:30:45.666 - Send marker again
23:30:45.666 - Incoming Stream Cache -> InputTrafficHelper
23:30:45.667 - ReindexForItvAfterTransport -> TimeShift reindex
23:30:45.667 - TimeShift.Cursor reset
23:30:45.667 - ReaderCursor::Reset.. OK
23:30:45.668 - Prereader.Reset ...-OK
23:30:45.688 - Prereader.Exit23:30:45.690 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
23:30:45.690 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=41943040
23:30:45.691 - SCH 0
23:30:45.691 - TCS set 0
23:30:45.691 - TCS set 1
23:30:45.692 - TCS set 2
23:30:45.692 - TCS set 3
23:30:45.692 - TCS set 4
23:30:45.693 - TimeShift.Cursor SetChannel pidmask=00000000 first=1
23:30:45.693 - TimeShift.Cursor SetChannel::v 0 (27738)
23:30:45.693 - ReaderCursor::SetChannel.. OK
23:30:45.694 - ReaderCursor::SetPosition 0 (w=119133802856448)
23:30:45.694 - Prereader.SetChannel..
23:30:45.695 - Prereader.SetChannel unmask 1
23:30:45.695 - TCS set 5
23:30:45.695 - TCS set 6
23:30:45.696 - Plugin::SetChannel 43981
23:30:45.696 - TCS set 7
23:30:45.696 - TransponderManager::SelectChannel
23:30:45.697 - Api2BaseModule.UpdateAllOpenedChannels 1
23:30:45.697 - TCS set 8
23:30:45.697 - TCS set 9
23:30:45.699 - SCH 1
23:30:45.700 - GraphState: Undef by StartChannelIsReady
23:30:45.701 - PreScanner.StartChannelIsReady net=1 parseall=1
23:30:45.701 - GraphState: RequestTransponderMark by StartChannelIsReady ITV
23:30:45.701 - SCH 2
23:30:45.702 - PreScanner.OnTransportMarker Radio packets
23:30:45.702 - EngineTransportsParser -> EngineTransportsParserRouter
23:30:45.702 - !marker: audio {mp3-parsing} video {--none} items( MediaType:0:len=0 )
23:30:45.703 - GraphState: RequestMediaTypeDetection by OnTransportMarker Not ready
23:30:45.703 - SCH 0
23:30:45.703 - TCS set 0
23:30:45.704 - TCS set 1
23:30:45.704 - TCS set 2
23:30:45.704 - TCS set 3
23:30:45.705 - TCS set 4
23:30:45.705 - TimeShift.Cursor SetChannel pidmask=00000000 first=0
23:30:45.705 - TimeShift.Cursor SetChannel::v 27738 (27738)
23:30:45.706 - Prereader.SetChannel unmask 1
23:30:45.706 - TCS set 5
23:30:45.706 - TCS set 6
23:30:45.706 - Plugin::SetChannel 43981
23:30:45.707 - TCS set 7
23:30:45.707 - TransponderManager::SelectChannel
23:30:45.707 - Api2BaseModule.UpdateAllOpenedChannels 1
23:30:45.708 - TCS set 8
23:30:45.708 - TCS set 9
23:30:45.708 - SCH 1
23:30:45.709 - PreScanner.StartChannelIsReady net=1 parseall=0
23:30:45.709 - SCH 2
23:30:45.709 - SCH 3
23:30:45.709 - LngDefaultSelector.try select audio pid=0 type=1 lng=0-(enm;ang;eng,deu;gmh;goh;ger,rus) l=99
23:30:45.710 - ChannelGraph::AfterDetectChannelParams
23:30:45.710 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
23:30:45.710 - TimeShift.Cursor PushMode 1 1 (pos=27) [StartChannelHelperBase(sl)]
23:30:45.711 - Prereader::ReaderCursor.. OK
23:30:45.711 - ReaderCursor::SetPosition 27738 (w=119133802856448)
23:30:45.712 - Prereader.StartPlayback Push buffering=0 recv=0C80CD9C [StartChannelHelperBase(sl)]
23:30:45.712 - Prereader.StartPlayback after lock
23:30:45.712 - PreScanner.StartPmtParserIfNeed
23:30:45.712 - PlayTimePmtParser::StopParser
23:30:45.714 - --SwitchEngineTransport end
23:30:45.729 - AudioParser::First packet 2000
23:30:45.729 - PreScanner.OnFirstItvTraffic
23:30:45.730 - AudioParser::MP3 detected
23:30:45.731 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
23:30:45.731 - PreScanner.Post_InitRender [ReceiveTraffic]
23:30:45.731 - 
23:30:45.732 - [101]       #################### Init Render. (62.078ms) #######################
23:30:45.732 - 
23:30:45.732 - selector PlaybackTag=none videocodec=FFmpegS/ ts=0
23:30:45.733 - Radio
23:30:45.733 - Channel PlaybackWay after check FFmpegS
23:30:45.734 - GraphState: GraphStarted by AsyncInitRender
23:30:45.734 - CalculBufferingSize prov=0C71234C marker=1 request=1
23:30:45.734 - CalculBuffering 800ms br=48kb to 4 kbytes. from marker
23:30:45.735 - TimeShift.Cursor PushMode 2 0 (pos=0) [EnablePullModeAndStartPlayback]
23:30:45.735 - Prereader::ReaderCursor.. OK
23:30:45.736 - ReaderCursor::SetPosition 0 (w=244194660581376)
23:30:45.736 - Prereader.StartPlayback Pull buffering=4915 recv=00000000 [EnablePullModeAndStartPlayback]
23:30:45.736 - Prereader.StartPlayback after lock
23:30:45.737 - Buffering/prereader size 4/976 kbytes
23:30:45.737 - Not same player! old 0 new 3 prev 13
23:30:45.737 - call recreate of surface
23:30:45.738 - ------sGetVideoParams
23:30:45.739 - Create FFMpegTvPlayer
23:30:45.740 - ITV demux -> ItvCorrectionDemux
23:30:45.740 - before render create or reset m=3 radio=1
23:30:45.741 - [101]AV:UpdateAudioParamsAfterActive
23:30:45.741 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
23:30:45.741 - GR:UpdateAudioProcessor old=0 new=0
23:30:45.742 - CPCG SetVolume 0DF8E410 0
23:30:45.742 - subsm.StartChannel demux=FFmpeg tr=AVPackets
23:30:45.742 - subsm.RecreateSubtitlesParsers 0DF8E428 ts=0 subindex=-1 st=-1
23:30:45.743 - subsm.RecreateSubtitlesParsers end
23:30:45.744 - subs: OnSetChannel
23:30:45.744 - Delete timer 100 Subtitles
23:30:45.745 - ------sGetVideoParams
23:30:45.781 - * s:100 q:100 tr=42308 (0) - ntt0 dmx-2147483648 -1 Preparing
23:30:45.863 - ARCP: Start tv=0 channels=1 startTime=0.000
23:30:45.889 - 
23:30:45.889 - [0]########################### Mark of finish channel changing. (62.235ms) #############
23:30:45.889 - 
23:30:45.891 - ------sGetVideoParams
23:30:46.596 - * s:100 q:100 tr=50842 (0) - ntt0 dmx1044 -1 Playing
23:30:47.110 - * s:100 q:100 tr=0 (0) - ntt0 dmx522 -1 Playing
23:30:47.610 - * s:100 q:100 tr=8180 (0) - ntt0 dmx26 -1 Playing
23:30:48.110 - * s:0 q:100 tr=0 (0) - ntt0 dmx0 -1 Buffering
23:30:48.615 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:30:49.104 - * s:100 q:100 tr=0 (0) - ntt0 dmx1253 -1 Playing
23:30:49.628 - * s:100 q:100 tr=0 (0) - ntt0 dmx1253 -1 Playing
23:30:50.118 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:30:50.638 - * s:100 q:100 tr=0 (0) - ntt0 dmx1253 -1 Playing
23:30:51.640 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:30:52.144 - * s:100 q:100 tr=0 (0) - ntt0 dmx966 -1 Playing
23:30:52.651 - * s:100 q:100 tr=0 (0) - ntt0 dmx444 -1 Playing
23:30:53.660 - * s:0 q:100 tr=8180 (0) - ntt0 dmx0 -1 Buffering
23:30:54.662 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:30:55.668 - * s:100 q:100 tr=8180 (0) - ntt0 dmx1253 -1 Playing
23:30:56.681 - * s:100 q:100 tr=0 (0) - ntt0 dmx1253 -1 Playing
23:30:57.695 - * s:100 q:100 tr=8180 (0) - ntt0 dmx862 -1 Playing
23:30:58.564 - * s:100 q:100 tr=8180 (0) - ntt0 dmx0 -1 Playing
23:30:59.545 - 
23:30:59.545 - Stop engine
23:30:59.546 - DevicesManager::Stop
23:30:59.547 - AV::Done0
23:30:59.547 - [101]Stop channel
23:30:59.548 - PreScanner.Stop
23:30:59.548 - GraphState: Undef by PreScanner.Stop
23:30:59.548 - PlayTimePmtParser::StopParser
23:30:59.549 - TimeShift.Cursor PushMode 0 0 (pos=0) [Engine5ChannelBase.StopChannel]
23:30:59.550 - Prereader::ReaderCursor.. OK
23:30:59.550 - ReaderCursor::SetPosition 0 (w=786408511897600)
23:30:59.551 - Prereader.StartPlayback Disable buffering=0 recv=00000000 [Engine5ChannelBase.StopChannel]
23:30:59.551 - Prereader.StartPlayback after lock
23:30:59.552 - subsm.StopChannel
23:30:59.553 - SS_CC: SetChannel ts2pes
23:30:59.554 - DestroyPlayer th=0
23:30:59.556 - ARCP: Stop
23:30:59.584 - ARCP: Stop
23:30:59.585 - DestroyPlayer - after
23:30:59.585 - AV::Done1
23:30:59.585 - subsm.StopChannel
23:30:59.585 - SS_CC: SetChannel ts2pes
23:30:59.586 - DestroyPlayer th=0
23:30:59.586 - DestroyPlayer - after
23:30:59.586 - TransponderManager::CloseChannel
23:30:59.586 - ~TransponderChannelSource
23:30:59.586 - TimeShift.DestroyReadingCursor
23:30:59.597 - Prereader.Exit23:30:59.598 - Api2BaseModule.UpdateAllOpenedChannels 0
23:30:59.598 - ~ChannelPlaybackGraph
23:30:59.598 - Destroy TSTS filter pid=0
23:30:59.599 - PlayTimePmtParser::StopParser
23:30:59.599 - PlayTimePmtParser::StopParser
23:30:59.599 - Destroy TSTS filter pid=4095
23:30:59.599 - AV::Done2
23:30:59.599 - AV::Done end
23:30:59.600 - DevicesManager::Free devices
23:30:59.600 - SDP:Destroy netclient.0 own=0C789CF8
23:30:59.600 - SetOwner N -> 0
23:30:59.600 - SetOwner SetTransponder null
23:30:59.690 - SDP:before destroy trm
23:30:59.690 - ~TransponderManager
23:30:59.690 - Delete timer 1000 BaseTransponderManaer
23:30:59.690 - SetOwner N -> 0
23:30:59.690 - SetOwner SetTransponder null
23:30:59.691 - TimeShift.~TimeShift
23:30:59.692 - HugeMemoryBlocksManager::Free
23:30:59.692 - SDP:after tr
23:30:59.692 - SDP:before destroy device
23:30:59.693 - SDP:after destroy device
23:30:59.693 - SDP:after destroy device
23:30:59.693 - DevicesManager::Free RC
23:30:59.694 - Engine stopped
23:30:59.862 - !m_D3DDevices.size()
