20:47:34.132 - Start logging...
20:47:34.264 - Created thread: counter=1 id=0x0x5862f8a8 AsyncCallManager
20:47:34.399 - ADEV: Create netclient.0
20:47:34.407 - dev: Created  netclient.0 0x586e3400
20:47:34.439 - Api2BaseModule.GetAdditionInfo
20:47:34.579 - AV:UpdateAudioParamsAfterActive
20:47:34.793 - 
20:47:34.793 - ########################### Mark of start channel changing.###########################
20:47:34.793 - 
20:47:34.794 - Delay Set channel because no surface
20:47:34.794 - 
20:47:34.794 - 
20:47:34.794 - ########################### Mark of start channel changing.###########################
20:47:34.794 - 
20:47:34.796 - GraphManager::MainChangeChannel СТС +4  1 provs
20:47:34.796 - DevicesManager::DestroyAllTransponderManagerUsedForSetTransponders
20:47:34.796 - SetChannelLogic::SetChannel 0
20:47:34.796 - SetChannelLogic::SetNormalChannel
20:47:34.796 - DevicesManager::CreateCloneDevice
20:47:34.797 - DevicesManager::CreateCloneDevice OK - founded in list
20:47:34.797 - AndroidTransponderManager created
20:47:34.797 - TrManager: NetClient
20:47:34.797 - CTransponderManager::InitOnNewDevice 0x5aa0f00c
20:47:34.798 - Created thread: counter=2 id=0x0x586e90a0 Incoming Stream Cache
20:47:34.798 - NetClient -> Incoming Stream Cache
20:47:34.799 - TrafficProcessingScheme_CreateForNewEngineTransport 1 ss=0
20:47:34.800 - Normal TsInputTrafficProcessor.
20:47:34.815 - FilterManager::InitOnNewDevice 1
20:47:34.821 - TS router+MarkerFinder -> InputTrafficProcessor
20:47:34.824 - Incoming Stream Cache -> TS router+MarkerFinder
20:47:34.825 - InputTrafficProcessor -> Router to timeshift
20:47:34.825 - InputTrafficProcessor(FM) -> FilterManager
20:47:34.825 - AndroidChannelPlayback created
20:47:34.826 - TimeShift.TimeShift
20:47:34.826 - TimeShift.Create 100 mbytes 
20:47:34.826 - ts:One channel
20:47:34.827 - HugeMemoryBlocksManager::GetBlock 100 mbytes OK
20:47:34.827 - TransponderManager::CreateTimeshiftBuffer OK
20:47:34.827 - TimeShift.CreateReadingCursor
20:47:34.828 - Created thread: counter=3 id=0x0x586e9ca0 PrereaderCacheBuffer
20:47:34.828 - TsInputTrafficProcessor.AddChannel 0
20:47:34.828 - SetChannelLogic::LookDeviceWithoutChannelsOrCloneAndMakeGraph
20:47:34.828 - add graph 0x5ad60ea4 101
20:47:34.828 - AV:UpdateAudioParamsAfterActive
20:47:34.829 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
20:47:34.829 - GR:UpdateAudioProcessor old=0 new=0
20:47:34.829 - 
20:47:34.829 - TransponderManager::SetTransponder (CH) 0 СТС +4
20:47:34.829 - *Transport TS
20:47:34.829 - Api2Device::SetChannel CHANNEL_DELAYED_URL
20:47:34.836 - Api2Device::SetChannel result OK
20:47:34.846 - TrafficProcessingScheme_OnSetChannelOrTransponder ch=43981
20:47:34.846 - TsInputTrafficProcessor.OpenTransponder 1
20:47:34.847 - FilterManager::OnSetTransponder SetChannel
20:47:34.848 - TimeShift.Reset
20:47:34.848 - TimeShift.Cursor reset
20:47:34.848 - ReaderCursor::Reset.. OK
20:47:34.848 - Prereader.Reset ...-OK
20:47:34.849 - AV:UpdateAudioParamsAfterActive
20:47:34.849 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
20:47:34.849 - GR:UpdateAudioProcessor old=0 new=0
20:47:34.849 - CChannelGraph5::StartChannel СТС +4
20:47:34.849 - 
20:47:34.849 -        #################### Start stream. (0.055ms) #######################
20:47:34.849 - 
20:47:34.850 - TimeShift.Cursor SetChannel pidmask=0x6116f930 first=1
20:47:34.851 - TimeShift.Cursor SetChannel::v 0 (0)
20:47:34.851 - ReaderCursor::SetChannel.. OK
20:47:34.851 - ReaderCursor::SetPosition 0 (w=0)
20:47:34.851 - Prereader.SetChannel..
20:47:34.852 - PMT convertor
20:47:34.852 - Prereader.SetChannel mask. SimplePatPmtConvertor
20:47:34.852 - PPC.by mask. onlyV1A=0 No change PMT ,0,100
20:47:34.852 - TsInputTrafficProcessor.UpdateChannel 43981
20:47:34.853 - GraphState: Undef by StartChannelIsReady
20:47:34.854 - PreScanner.StartChannelIsReady net=1 parseall=1
20:47:34.854 - VideoMediaTypeDetection: mpeg2
20:47:34.854 - SetMarkerAudioLngStream Gen New stream
20:47:34.855 - SimpleTsDemux::Init apid=-1, vpid=100
20:47:34.855 - GraphState: RequestPATScan by StartChannelIsReady TS
20:47:34.855 - Api2BaseModule.Filters_EnabledFullTS 1
20:47:34.857 - Api2BaseModule.OnTunerStateChanged Preparing
20:47:34.858 - Api2BaseModule.OnTunerStateChanged 0
20:47:34.858 - Api2BaseModule.OnTunerStateChanged 1
20:47:34.858 - Api2BaseModule.OnTunerStateChanged 2
20:47:34.858 - Api2BaseModule.OnTunerStateChanged 3
20:47:34.862 - Api2BaseModule.OnTunerStateChanged Playing
20:47:34.862 - Api2BaseModule.OnTunerStateChanged 0
20:47:34.862 - Api2BaseModule.OnTunerStateChanged 1
20:47:34.862 - Api2BaseModule.OnTunerStateChanged 2
20:47:34.863 - Api2BaseModule.OnTunerStateChanged 3
20:47:34.857 - PlayTimePATParser.OpenParser 43981
20:47:34.866 - Open TSTS filter pid=0 data=0
20:47:34.866 - TSTS cur size=0
20:47:34.867 - AV:UpdateAudioParamsAfterActive
20:47:34.867 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
20:47:34.867 - GR:UpdateAudioProcessor old=0 new=0
20:47:34.867 - OK. 101
20:47:34.941 - Media_SetSurface before lock 0x1d20179a
20:47:34.941 - Media_SetSurface after lock 0x1d20179a
20:47:34.943 - BefoerNewSurface
20:47:34.944 - NWM. SetSurface 0x1d20179a env=0x40d7e9b0
20:47:34.944 - NWM: *** ANativeWindow_fromSurface.
20:47:34.951 - Set delayed channel after surface
20:47:34.951 - 
20:47:34.951 - 
20:47:34.952 - ########################### Apply url .###########################
20:47:34.952 - 
20:47:34.952 - GraphManager::MainChangeChannel СТС +4  1 provs
20:47:34.952 - Api2Device::SetChannel udp://@225.0.3.182:4001
20:47:34.953 - Api2Device::SetChannel result OK
20:47:34.953 - AfterNewSurface
20:47:34.954 - AfterNewSurface. after
20:47:34.956 - Api2BaseModule.OnTunerStateChanged Preparing
20:47:34.956 - Api2BaseModule.OnTunerStateChanged 0
20:47:34.956 - Api2BaseModule.OnTunerStateChanged 1
20:47:34.956 - Api2BaseModule.OnTunerStateChanged 2
20:47:34.961 - Api2BaseModule.OnTunerStateChanged 3
20:47:34.969 - Media_SetSurface before lock 0x1d20179a
20:47:34.969 - Media_SetSurface after lock 0x1d20179a
20:47:34.969 - Media_SetSurface Skiped. Same.
20:47:35.006 - Api2BaseModule.OnTunerStateChanged Playing
20:47:35.006 - Api2BaseModule.OnTunerStateChanged 0
20:47:35.006 - Api2BaseModule.OnTunerStateChanged 1
20:47:35.007 - Api2BaseModule.OnTunerStateChanged 2
20:47:35.007 - Api2BaseModule.OnTunerStateChanged 3
20:47:35.017 - TransportMarker: TS
20:47:35.018 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=104857600
20:47:35.020 - --SwitchEngineTransport TS > TS
20:47:35.020 - TimeShiftBuffer.SetContentLenght TimeshiftOverNetModule disabled req=0 size=104857600
20:47:35.020 - PreScanner.OnTransportMarker TS
20:47:35.021 - --SwitchEngineTransport end
20:47:35.029 - PlayTimePATParser.EndTask
20:47:35.107 - PreScanner.OnAfterScanPat count=2 {14;79}
20:47:35.107 - Api2BaseModule.Filters_EnabledFullTS 0
20:47:35.107 - GraphState: RequestPMTScan by OnAfterScanPat
20:47:35.108 - PlayTimePmtParser::OpenParser SID=14 PMT=79 unknow channel=1
20:47:35.108 - Open TSTS filter pid=79 data=2
20:47:35.108 - TSTS cur size=47564
20:47:35.108 - TSTS quick finish
20:47:35.109 - PreScanner.OnAfterScanPmt pmt=79
20:47:35.109 - GraphState: RequestMediaTypeDetection by OnAfterScanPmt
20:47:35.109 - PlayTimePmtParser::StopParser
20:47:35.113 - TimeShift.Cursor SetChannel pidmask=0x6116f930 first=0
20:47:35.113 - TimeShift.Cursor SetChannel::v 47564 (47564)
20:47:35.113 - Prereader.SetChannel mask. SimplePatPmtConvertor
20:47:35.113 - PPC.by mask. onlyV1A=0 No change PMT ,0,76,78,79
20:47:35.114 - TsInputTrafficProcessor.UpdateChannel 14
20:47:35.115 - PreScanner.StartChannelIsReady net=1 parseall=0
20:47:35.115 - LngDefaultSelector.try select audio pid=76 type=1 lng=0-(rus,rus,rus) l=99
20:47:35.115 - ChannelGraph5::AfterDetectChannelParams
20:47:35.116 - VideoMediaTypeDetection: h.264
20:47:35.116 - SimpleTsDemux::Init apid=76, vpid=78
20:47:35.116 - GraphState: RequestMediaTypeDetection by OnAfterDetectChannelParams
20:47:35.116 - TimeShift.Cursor PushMode 1 1 (pos=0) [StartChannelHelperBase(sl)]
20:47:35.117 - Prereader::ReaderCursor.. OK
20:47:35.117 - ReaderCursor::SetPosition 0 (w=50196)
20:47:35.117 - Prereader.StartPlayback Push buffering=0 recv=0x5ad61650 [StartChannelHelperBase(sl)]
20:47:35.117 - Prereader.StartPlayback after lock
20:47:35.123 - VideoParser::First packet 23649 h.264
20:47:35.125 - VideoParser::packet 33026
20:47:35.158 - VideoParser::packet 49556
20:47:35.166 - AudioParser::First packet 4608
20:47:35.167 - AudioParser::MPG12 detected
20:47:35.202 - VideoParser::packet 66049
20:47:35.258 - VideoParser::packet 89310
20:47:35.280 - VideoParser::packet 99075
20:47:35.318 - VideoParser::packet 115609
20:47:35.358 - VideoParser::packet 132134
20:47:35.415 - VideoParser::packet 155019
20:47:35.440 - VideoParser::packet 165220
20:47:35.479 - VideoParser::packet 181770
20:47:35.524 - VideoParser::packet 198311
20:47:35.572 - VideoParser::packet 220080
20:47:35.601 - VideoParser::packet 231421
20:47:35.632 - * s:100 q:100 tr=292340 (0) Preparing
20:47:35.640 - VideoParser::packet 247979
20:47:35.679 - VideoParser::packet 264500
20:47:35.730 - VideoParser::packet 286169
20:47:35.756 - VideoParser::packet 297474
20:47:35.798 - VideoParser::packet 313964
20:47:35.842 - VideoParser::packet 330445
20:47:35.939 - VideoParser::packet 372138
20:47:35.944 - h264 detected 821 ms
20:47:35.946 - GraphState: GraphCreating by ReceiveTraffic !RequestAudioMediaType
20:47:35.946 - PreScanner.Post_InitRender [ReceiveTraffic]
20:47:35.946 - ------sGetVideoParams
20:47:35.947 - PlaybackTag=none
20:47:35.947 - PlaybackWay after check 1
20:47:35.955 - CalculBufferingSize prov=0x5aa10250 marker=1 request=0
20:47:35.956 - GraphState: GraphStarted by AsyncInitRender
20:47:35.956 - TimeShift.Cursor PushMode 2 0 (pos=0) [AsyncInitRender]
20:47:35.957 - Prereader::ReaderCursor.. OK
20:47:35.957 - ReaderCursor::SetPosition 0 (w=444996)
20:47:35.957 - Prereader.StartPlayback Pull buffering=0 recv=0x0 [AsyncInitRender]
20:47:35.957 - Prereader.StartPlayback after lock
20:47:35.962 - Not same player! old 0 new 1 prev 0
20:47:35.962 - ClearSurface. before lock between renders
20:47:35.964 - ClearSurface between renders count:0 size:64
20:47:35.966 - call recreate of surface
20:47:35.991 - Media_SetSurface before lock 0x0
20:47:35.991 - Media_SetSurface after lock 0x0
20:47:35.992 - BefoerNewSurface
20:47:35.992 - NWM. SetSurface 0x0 env=0x40d7e9b0
20:47:35.992 - NWM. release 0x5ace9bd0 SetSurface th=0
20:47:35.992 - NWM: *** ANativeWindow_release 0
20:47:35.993 - AfterNewSurface
20:47:35.993 - AfterNewSurface. after
20:47:36.009 - Media_SetSurface before lock 0x1d20179a
20:47:36.010 - Media_SetSurface after lock 0x1d20179a
20:47:36.010 - BefoerNewSurface
20:47:36.010 - NWM. SetSurface 0x1d20179a env=0x40d7e9b0
20:47:36.010 - NWM: *** ANativeWindow_fromSurface.
20:47:36.010 - AfterNewSurface
20:47:36.010 - AfterNewSurface. after
20:47:36.021 - Media_SetSurface before lock 0x1d20179a
20:47:36.021 - Media_SetSurface after lock 0x1d20179a
20:47:36.021 - Media_SetSurface Skiped. Same.
20:47:36.038 - ::OpenMaxPlayer normal
20:47:36.207 - PMT convertor
20:47:36.207 - PPC.gen mask.
20:47:36.207 - PPC.Audio pid 76
20:47:36.207 - PPC.Audio PID 76 type=1
20:47:36.208 - Created thread: counter=5 id=0x0x5aa70ac8 OpenMAX traffic reader
20:47:36.208 - subsm.StartChannel demux=OpenMAX tr=TS
20:47:36.209 - subsm.RecreateSubtitlesParsers 0x61acb2ec ts=1 subindex=-1
20:47:36.209 - subsm.Set Teletext parser 0
20:47:36.209 - subsm.RecreateSubtitlesParsers end
20:47:36.209 - subs: OnSetChannel
20:47:36.209 - AV:UpdateAudioParamsAfterActive
20:47:36.209 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
20:47:36.210 - GR:UpdateAudioProcessor old=0 new=0
20:47:36.210 - ------sGetVideoParams
20:47:36.235 - ------sGetVideoParams
20:47:36.240 - NWM. Create {OMX.CreatePlayer} th=1 obj=0x1d901696 env=0x57075418 win=0x5ace9bd0 chgen=2
20:47:36.243 - AV:UpdateAudioParamsAfterActive
20:47:36.243 - AV:UpdateAudioProcessor spec=0 agc=0 bands=0
20:47:36.243 - GR:UpdateAudioProcessor old=0 new=0
20:47:36.243 - ------sGetVideoParams
20:47:36.591 - * s:100 q:100 tr=193452 (0) Playing
20:47:36.613 - ------sGetVideoParams
20:47:36.688 - Media_SetSurface before lock 0x1d20179a
20:47:36.689 - Media_SetSurface after lock 0x1d20179a
20:47:36.689 - Media_SetSurface Skiped. Same.
20:47:36.715 - ------sGetVideoParams
20:47:36.717 - ------sGetVideoParams
20:47:37.372 - * s:100 q:100 tr=0 (0) Playing
20:47:38.374 - * s:100 q:100 tr=0 (0) Playing
20:47:39.377 - * s:100 q:100 tr=0 (0) Playing
20:47:40.379 - * s:100 q:100 tr=0 (0) Playing
20:47:41.382 - * s:0 q:0 tr=0 (0) Playing
20:47:42.424 - * s:0 q:0 tr=0 (0) Playing
20:47:43.426 - * s:0 q:0 tr=0 (0) Playing
20:47:44.430 - * s:0 q:0 tr=0 (0) Playing
20:47:45.433 - * s:0 q:0 tr=0 (0) Playing
20:47:46.331 - ------sGetVideoParams
20:47:46.436 - * s:0 q:0 tr=0 (0) Playing
20:47:47.439 - * s:0 q:0 tr=0 (0) Playing
20:47:48.442 - * s:0 q:0 tr=0 (0) Playing
20:47:49.445 - * s:0 q:0 tr=0 (0) Playing
20:47:50.507 - * s:0 q:0 tr=0 (0) Playing
20:47:51.512 - * s:0 q:0 tr=0 (0) Playing
20:47:52.515 - * s:0 q:0 tr=0 (0) Playing
20:47:53.518 - * s:0 q:0 tr=0 (0) Playing
20:47:54.525 - * s:0 q:0 tr=0 (0) Playing
20:47:55.530 - * s:0 q:0 tr=0 (0) Playing
20:47:56.534 - * s:0 q:0 tr=0 (0) Playing
20:47:56.803 - ~AndroidChannelPlayback
20:47:56.803 - subsm.StopChannel
20:47:56.803 - DestoryPlayer th=0
20:47:56.808 - NWM. release 0x5ace9bd0 OpenMaxInterfaces.Destroy th=0
20:47:56.808 - Stoping thread OpenMAX traffic reader
20:47:56.867 - OK
20:47:56.871 - NWM. release 0x5ace9bd0 OpenMaxInterfaces.Destroy th=0
20:47:56.872 - AR.destroy buffers
20:47:56.875 - DestoryPlayer - after
20:47:56.876 - TransponderManager::CloseChannel
20:47:56.876 - TsInputTrafficProcessor.RemoveChannel 14
20:47:56.877 - ~TransponderChannelSource
20:47:56.882 - TimeShift.DestroyReadingCursor
20:47:56.883 - Stoping thread PrereaderCacheBuffer
20:47:56.883 - OK
20:47:56.885 - Destroy TSTS filter pid=0
20:47:56.885 - PlayTimePmtParser::StopParser
20:47:56.885 - Destroy TSTS filter pid=79
20:47:56.886 - remove graph idx=0 count=1
20:47:56.932 - Media_SetSurface before lock 0x0
20:47:56.932 - Media_SetSurface after lock 0x0
20:47:56.932 - NWM. SetSurface 0x0 env=0x40d7e9b0
20:47:56.932 - NWM. release 0x5ace9bd0 SetSurface th=0
20:47:56.932 - NWM: *** ANativeWindow_release 0
20:47:56.939 - SDP:Destroy netclient.0 own=0x5aa70c30
20:47:56.940 - NetClient -> 0
20:47:56.942 - ~AndroidTransponderManager
20:47:56.942 - NetClient -> 0
20:47:56.942 - Stoping thread Incoming Stream Cache
20:47:56.943 - OK
20:47:56.945 - TimeShift.~TimeShift
20:47:56.945 - HugeMemoryBlocksManager::Free
20:47:56.947 - SDP:after tr
20:47:56.947 - SDP:after destroy device
20:47:56.948 - NWM. Stop th=401123dc #0
20:47:56.950 - NWM. release 0x0 Stop th=0
