// Copyright 1998-2014 Epic Games, Inc. All Rights Reserved. #include "ProfilerPrivatePCH.h" /*----------------------------------------------------------------------------- FProfilerStat, FProfilerGroup -----------------------------------------------------------------------------*/ FProfilerStat FProfilerStat::Default; FProfilerGroup FProfilerGroup::Default; FProfilerStat::FProfilerStat( const uint32 InStatID /*= 0 */ ) : _Name( TEXT("(Stat-Default)") ) , _OwningGroupPtr( FProfilerGroup::GetDefaultPtr() ) , _ID( InStatID ) , _Type( EProfilerSampleTypes::InvalidOrMax ) {} /*----------------------------------------------------------------------------- FProfilerSession -----------------------------------------------------------------------------*/ FProfilerSession::FProfilerSession( EProfilerSessionTypes::Type InSessionType, const ISessionInstanceInfoPtr InSessionInstanceInfo, FGuid InSessionInstanceID, FString InDataFilepath ) : ClientStatMetadata( nullptr ) , bRequestStatMetadataUpdate( false ) , bLastPacket( false ) , StatMetaDataSize( 0 ) , OnTick( FTickerDelegate::CreateRaw( this, &FProfilerSession::HandleTicker ) ) , DataProvider( MakeShareable( new FArrayDataProvider() ) ) , StatMetaData( MakeShareable( new FProfilerStatMetaData() ) ) , EventGraphDataTotal( MakeShareable( new FEventGraphData() ) ) , EventGraphDataMaximum( MakeShareable( new FEventGraphData() ) ) , EventGraphDataCurrent( MakeShareable( new FEventGraphData() ) ) , CreationTime( FDateTime::Now() ) , SessionType( InSessionType ) , SessionInstanceInfo( InSessionInstanceInfo ) , SessionInstanceID( InSessionInstanceID ) , DataFilepath( InDataFilepath ) , bDataPreviewing( false ) , bDataCapturing( false ) , bHasAllProfilerData( false ) , FPSAnalyzer( MakeShareable( new FFPSAnalyzer( 5, 0, 60 ) ) ) {} FProfilerSession::FProfilerSession( const ISessionInstanceInfoPtr InSessionInstanceInfo ) : ClientStatMetadata( nullptr ) , bRequestStatMetadataUpdate( false ) , bLastPacket( false ) , StatMetaDataSize( 0 ) , OnTick( FTickerDelegate::CreateRaw( this, &FProfilerSession::HandleTicker ) ) , DataProvider( MakeShareable( new FArrayDataProvider() ) ) , StatMetaData( MakeShareable( new FProfilerStatMetaData() ) ) , EventGraphDataTotal( MakeShareable( new FEventGraphData() ) ) , EventGraphDataMaximum( MakeShareable( new FEventGraphData() ) ) , EventGraphDataCurrent( MakeShareable( new FEventGraphData() ) ) , CreationTime( FDateTime::Now() ) , SessionType( EProfilerSessionTypes::Live ) , SessionInstanceInfo( InSessionInstanceInfo ) , SessionInstanceID( InSessionInstanceInfo->GetInstanceId() ) , DataFilepath( TEXT("") ) , bDataPreviewing( false ) , bDataCapturing( false ) , bHasAllProfilerData( false ) , FPSAnalyzer( MakeShareable( new FFPSAnalyzer( 5, 0, 60 ) ) ) { // Randomize creation time to test loading profiler captures with different creation time and different amount of data. CreationTime = FDateTime::Now() += FTimespan( 0, 0, FMath::RandRange( 2, 8 ) ); FTicker::GetCoreTicker().AddTicker( OnTick ); } FProfilerSession::FProfilerSession( const FString& InDataFilepath ) : ClientStatMetadata( nullptr ) , bRequestStatMetadataUpdate( false ) , bLastPacket( false ) , StatMetaDataSize( 0 ) , OnTick( FTickerDelegate::CreateRaw( this, &FProfilerSession::HandleTicker ) ) , DataProvider( MakeShareable( new FArrayDataProvider() ) ) , StatMetaData( MakeShareable( new FProfilerStatMetaData() ) ) , EventGraphDataTotal( MakeShareable( new FEventGraphData() ) ) , EventGraphDataMaximum( MakeShareable( new FEventGraphData() ) ) , EventGraphDataCurrent( MakeShareable( new FEventGraphData() ) ) , CreationTime( FDateTime::Now() ) , SessionType( EProfilerSessionTypes::StatsFile ) , SessionInstanceInfo( nullptr ) , SessionInstanceID( FGuid::NewGuid() ) , DataFilepath( InDataFilepath.Replace( *FStatConstants::StatsFileExtension, TEXT( "" ) ) ) , bDataPreviewing( false ) , bDataCapturing( false ) , bHasAllProfilerData( false ) , FPSAnalyzer( MakeShareable( new FFPSAnalyzer( 5, 0, 60 ) ) ) { // Randomize creation time to test loading profiler captures with different creation time and different amount of data. CreationTime = FDateTime::Now() += FTimespan( 0, 0, FMath::RandRange( 2, 8 ) ); FTicker::GetCoreTicker().AddTicker( OnTick ); } FProfilerSession::~FProfilerSession() { FTicker::GetCoreTicker().RemoveTicker( OnTick ); } FGraphDataSourceRefConst FProfilerSession::CreateGraphDataSource( const uint32 InStatID ) { FGraphDataSource* GraphDataSource = new FGraphDataSource( AsShared(), InStatID ); return MakeShareable( GraphDataSource ); } FEventGraphDataRef FProfilerSession::CreateEventGraphData( const uint32 FrameStartIndex, const uint32 FrameEndIndex, const EEventGraphTypes::Type EventGraphType ) { static FTotalTimeAndCount Current(0.0f, 0); PROFILER_SCOPE_LOG_TIME( TEXT( "FProfilerSession::CreateEventGraphData" ), &Current ); FEventGraphData* EventGraphData = new FEventGraphData(); if( EventGraphType == EEventGraphTypes::Average ) { for( uint32 FrameIndex = FrameStartIndex; FrameIndex < FrameEndIndex+1; ++FrameIndex ) { // Create a temporary event graph data for the specified frame. const FEventGraphData CurrentEventGraphData( AsShared(), FrameIndex ); EventGraphData->CombineAndAdd( CurrentEventGraphData ); } EventGraphData->Advance( FrameStartIndex, FrameEndIndex+1 ); EventGraphData->Divide( (double)EventGraphData->GetNumFrames() ); } else if( EventGraphType == EEventGraphTypes::Maximum ) { for( uint32 FrameIndex = FrameStartIndex; FrameIndex < FrameEndIndex+1; ++FrameIndex ) { // Create a temporary event graph data for the specified frame. const FEventGraphData CurrentEventGraphData( AsShared(), FrameIndex ); EventGraphData->CombineAndFindMax( CurrentEventGraphData ); } EventGraphData->Advance( FrameStartIndex, FrameEndIndex+1 ); } return MakeShareable( EventGraphData ); } void FProfilerSession::UpdateAggregatedStats( const uint32 FrameIndex ) { const FIntPoint& IndicesForFrame = DataProvider->GetSamplesIndicesForFrame( FrameIndex ); const uint32 SampleStartIndex = IndicesForFrame.X; const uint32 SampleEndIndex = IndicesForFrame.Y; const FProfilerSampleArray& Collection = DataProvider->GetCollection(); for( uint32 SampleIndex = SampleStartIndex; SampleIndex < SampleEndIndex; SampleIndex++ ) { const FProfilerSample& ProfilerSample = Collection[ SampleIndex ]; const uint32 StatID = ProfilerSample.StatID(); FProfilerAggregatedStat* AggregatedStat = AggregatedStats.Find( StatID ); if( !AggregatedStat ) { const FProfilerStat& ProfilerStat = GetMetaData()->GetStatByID( StatID ); AggregatedStat = &AggregatedStats.Add( ProfilerSample.StatID(), FProfilerAggregatedStat( ProfilerStat.Name(), ProfilerStat.OwningGroup().Name(), ProfilerSample.Type() ) ); } (*AggregatedStat) += ProfilerSample; } for( auto It = AggregatedStats.CreateIterator(); It; ++It ) { FProfilerAggregatedStat& AggregatedStat = It.Value(); AggregatedStat.Advance(); } // @TODO: Create map for stats TMap >; StatID -> Sample indices for faster lookup in data providers } void FProfilerSession::EventGraphCombineAndMax( const FEventGraphDataRef Current, const uint32 NumFrames ) { EventGraphDataMaximum->CombineAndFindMax( Current.Get() ); EventGraphDataMaximum->Advance( 0, NumFrames ); } void FProfilerSession::EventGraphCombineAndAdd( const FEventGraphDataRef Current, const uint32 NumFrames ) { EventGraphDataTotal->CombineAndAdd( Current.Get() ); EventGraphDataTotal->Advance( 0, NumFrames ); } void FProfilerSession::UpdateAggregatedEventGraphData( const uint32 FrameIndex ) { if( CompletionSync.GetReference() && !CompletionSync->IsComplete() ) { static FTotalTimeAndCount JoinTasksTimeAndCont(0.0f, 0); PROFILER_SCOPE_LOG_TIME( TEXT( "FProfilerSession::CombineJoinAndContinue" ), &JoinTasksTimeAndCont ); FTaskGraphInterface::Get().WaitUntilTaskCompletes(CompletionSync, ENamedThreads::GameThread); } static FTotalTimeAndCount TimeAndCount(0.0f, 0); PROFILER_SCOPE_LOG_TIME( TEXT( "FProfilerSession::UpdateAggregatedEventGraphData" ), &TimeAndCount ); // Create a temporary event graph data for the specified frame. EventGraphDataCurrent = MakeShareable( new FEventGraphData( AsShared(), FrameIndex ) ); const uint32 NumFrames = DataProvider->GetNumFrames(); static const bool bUseTaskGraph = true; if( bUseTaskGraph ) { FGraphEventArray EventGraphCombineTasks; new (EventGraphCombineTasks) FGraphEventRef(FSimpleDelegateGraphTask::CreateAndDispatchWhenReady ( FSimpleDelegateGraphTask::FDelegate::CreateRaw( this, &FProfilerSession::EventGraphCombineAndMax, EventGraphDataCurrent, NumFrames ), TEXT("EventGraphData.CombineAndFindMax"), nullptr )); new (EventGraphCombineTasks) FGraphEventRef(FSimpleDelegateGraphTask::CreateAndDispatchWhenReady ( FSimpleDelegateGraphTask::FDelegate::CreateRaw( this, &FProfilerSession::EventGraphCombineAndAdd, EventGraphDataCurrent, NumFrames ), TEXT("EventGraphData.EventGraphCombineAndAdd"), nullptr )); // JoinThreads CompletionSync = TGraphTask::CreateTask( &EventGraphCombineTasks, ENamedThreads::GameThread ) .ConstructAndDispatchWhenReady( TEXT("EventGraphData.CombineJoinAndContinue"), ENamedThreads::AnyThread ); } else { EventGraphCombineAndMax( EventGraphDataCurrent, NumFrames ); EventGraphCombineAndAdd( EventGraphDataCurrent, NumFrames ); } } bool FProfilerSession::HandleTicker( float DeltaTime ) { // Update metadata if needed if( bRequestStatMetadataUpdate ) { StatMetaData->Update( *ClientStatMetadata ); bRequestStatMetadataUpdate = false; } const int32 FramesNumToProcess = SessionType == EProfilerSessionTypes::StatsFile ? 240 : 2; for( int32 FrameNumber = 0; FrameNumber < FMath::Min(FrameToProcess.Num(),FramesNumToProcess); FrameNumber++ ) { const uint32 FrameIndex = FrameToProcess[0]; FrameToProcess.RemoveAt( 0 ); FProfilerDataFrame& CurrentProfilerData = FrameToProfilerDataMapping.FindChecked( FrameIndex ); static FTotalTimeAndCount Current(0.0f, 0); PROFILER_SCOPE_LOG_TIME( TEXT( "FProfilerSession::HandleTicker" ), &Current ); const FProfilerStatMetaDataRef MetaData = GetMetaData(); TMap ThreadMS; // Preprocess the hierarchical samples for the specified frame. const TMap& CycleGraphs = CurrentProfilerData.CycleGraphs; // Add a root sample for this frame. // HACK 2013-07-19, 13:44 STAT_Root == ThreadRoot in stats2 const uint32 FrameRootSampleIndex = DataProvider->AddHierarchicalSample( 0, MetaData->GetStatByID(1).OwningGroup().ID(), 1, 0.0f, 0.0f, 1 ); double GameThreadTimeMS = 0.0f; double MaxThreadTimeMS = 0.0f; double ThreadStartTimeMS = 0.0; for( auto ThreadIt = CycleGraphs.CreateConstIterator(); ThreadIt; ++ThreadIt ) { const uint32 ThreadID = ThreadIt.Key(); const FProfilerCycleGraph& ThreadGraph = ThreadIt.Value(); // Calculate total time for this thread. double ThreadDurationTimeMS = 0.0; ThreadStartTimeMS = CurrentProfilerData.FrameStart; for( int32 Index = 0; Index < ThreadGraph.Children.Num(); Index++ ) { ThreadDurationTimeMS += MetaData->ConvertCyclesToMS( ThreadGraph.Children[Index].Value ); } if (ThreadDurationTimeMS > 0.0) { // Check for game thread. const FString GameThreadName = FName( NAME_GameThread ).GetPlainNameString(); const bool bGameThreadFound = MetaData->GetThreadDescriptions().FindChecked( ThreadID ).Contains( GameThreadName ); if( bGameThreadFound ) { GameThreadTimeMS = ThreadDurationTimeMS; } // Add a root sample for each thread. const uint32& NewThreadID = MetaData->ThreadIDtoStatID.FindChecked( ThreadID ); const uint32 ThreadRootSampleIndex = DataProvider->AddHierarchicalSample ( NewThreadID/*ThreadID*/, MetaData->GetStatByID(NewThreadID).OwningGroup().ID(), NewThreadID, ThreadStartTimeMS, ThreadDurationTimeMS, 1, FrameRootSampleIndex ); ThreadMS.FindOrAdd( ThreadID ) = (float)ThreadDurationTimeMS; // Recursively add children and parent to the root samples. for( int32 Index = 0; Index < ThreadGraph.Children.Num(); Index++ ) { const FProfilerCycleGraph& CycleGraph = ThreadGraph.Children[Index]; const double CycleDurationMS = MetaData->ConvertCyclesToMS( CycleGraph.Value ); const double CycleStartTimeMS = ThreadStartTimeMS; if (CycleDurationMS > 0.0) { PopulateHierarchy_Recurrent( CycleGraph, CycleStartTimeMS, CycleDurationMS, ThreadRootSampleIndex ); } ThreadStartTimeMS += CycleDurationMS; } MaxThreadTimeMS = FMath::Max( MaxThreadTimeMS, ThreadDurationTimeMS ); } } // Fix the root stat time. FProfilerSampleArray& MutableCollection = const_cast(DataProvider->GetCollection()); MutableCollection[FrameRootSampleIndex].SetDurationMS( GameThreadTimeMS != 0.0f ? GameThreadTimeMS : MaxThreadTimeMS ); FPSAnalyzer->AddSample( GameThreadTimeMS > 0.0f ? 1000.0f/GameThreadTimeMS : 0.0f ); // Process the non-hierarchical samples for the specified frame. { // Process integer counters. for( int32 Index = 0; Index < CurrentProfilerData.CountAccumulators.Num(); Index++ ) { const FProfilerCountAccumulator& IntCounter = CurrentProfilerData.CountAccumulators[Index]; const EProfilerSampleTypes::Type ProfilerSampleType = MetaData->GetSampleTypeForStatID( IntCounter.StatId ); DataProvider->AddCounterSample( MetaData->GetStatByID(IntCounter.StatId).OwningGroup().ID(), IntCounter.StatId, (double)IntCounter.Value, ProfilerSampleType ); } // Process floating point counters. for( int32 Index = 0; Index < CurrentProfilerData.FloatAccumulators.Num(); Index++ ) { const FProfilerFloatAccumulator& FloatCounter = CurrentProfilerData.FloatAccumulators[Index]; DataProvider->AddCounterSample( MetaData->GetStatByID(FloatCounter.StatId).OwningGroup().ID(), FloatCounter.StatId, (double)FloatCounter.Value, EProfilerSampleTypes::NumberFloat ); } } // Advance frame const uint32 LastFrameIndex = DataProvider->GetNumFrames(); DataProvider->AdvanceFrame( MaxThreadTimeMS ); // Update aggregated stats UpdateAggregatedStats( LastFrameIndex ); // Update aggregated events. UpdateAggregatedEventGraphData( LastFrameIndex ); // Update mini-view. OnAddThreadTime.ExecuteIfBound( LastFrameIndex, ThreadMS, StatMetaData ); FrameToProfilerDataMapping.Remove( FrameIndex ); } if( SessionType == EProfilerSessionTypes::StatsFile ) { if( FrameToProcess.Num() == 0 && bHasAllProfilerData ) { // Broadcast that a capture file has been fully processed. OnCaptureFileProcessed.ExecuteIfBound( GetInstanceID() ); // Disable tick method as we no longer need to tick. return false; } } return true; } void FProfilerSession::PopulateHierarchy_Recurrent ( const FProfilerCycleGraph& ParentGraph, const double ParentStartTimeMS, const double ParentDurationMS, const uint32 ParentSampleIndex ) { const FProfilerStatMetaDataRef MetaData = GetMetaData(); const uint32& ThreadID = MetaData->ThreadIDtoStatID.FindChecked( ParentGraph.ThreadId ); const uint32 SampleIndex = DataProvider->AddHierarchicalSample ( ThreadID, MetaData->GetStatByID(ParentGraph.StatId).OwningGroup().ID(), ParentGraph.StatId, ParentStartTimeMS, ParentDurationMS, ParentGraph.CallsPerFrame, ParentSampleIndex ); double ChildStartTimeMS = ParentStartTimeMS; double ChildrenDurationMS = 0.0f; for( int32 DataIndex = 0; DataIndex < ParentGraph.Children.Num(); DataIndex++ ) { const FProfilerCycleGraph& ChildCyclesCounter = ParentGraph.Children[DataIndex]; const double ChildDurationMS = MetaData->ConvertCyclesToMS( ChildCyclesCounter.Value ); if (ChildDurationMS > 0.0) { PopulateHierarchy_Recurrent( ChildCyclesCounter, ChildStartTimeMS, ChildDurationMS, SampleIndex ); } ChildStartTimeMS += ChildDurationMS; ChildrenDurationMS += ChildDurationMS; } const double SelfTimeMS = ParentDurationMS - ChildrenDurationMS; if( SelfTimeMS > 0.0f && ParentGraph.Children.Num() > 0 ) { const FName& ParentStatName = MetaData->GetStatByID( ParentGraph.StatId ).Name(); const FName& ParentGroupName = MetaData->GetStatByID( ParentGraph.StatId ).OwningGroup().Name(); // Create a fake stat that represents this profiler sample's exclusive time. // This is required if we want to create correct combined event graphs later. DataProvider->AddHierarchicalSample ( ThreadID, MetaData->GetStatByID(0).OwningGroup().ID(), 0, // @see FProfilerStatMetaData.Update, 0 means "Self" ChildStartTimeMS, SelfTimeMS, 1, SampleIndex ); } } /*----------------------------------------------------------------------------- FRawProfilerSession -----------------------------------------------------------------------------*/ FRawProfilerSession::FRawProfilerSession( const FString& InRawStatsFileFileath ) : FProfilerSession( EProfilerSessionTypes::StatsFileRaw, nullptr, FGuid::NewGuid(), InRawStatsFileFileath.Replace( *FStatConstants::StatsFileRawExtension, TEXT( "" ) ) ) , CurrentMiniViewFrame( 0 ) { OnTick = FTickerDelegate::CreateRaw( this, &FRawProfilerSession::HandleTicker ); } FRawProfilerSession::~FRawProfilerSession() { FTicker::GetCoreTicker().RemoveTicker( OnTick ); } bool FRawProfilerSession::HandleTicker( float DeltaTime ) { #if 0 StatsThreadStats; Stream; enum { MAX_NUM_DATA_PER_TICK = 30 }; int32 NumDataThisTick = 0; // Add the data to the mini-view. for( int32 FrameIndex = CurrentMiniViewFrame; FrameIndex < Stream.FramesInfo.Num(); ++FrameIndex ) { const FStatsFrameInfo& StatsFrameInfo = Stream.FramesInfo[FrameIndex]; // Convert from cycles to ms. TMap ThreadMS; for( auto InnerIt = StatsFrameInfo.ThreadCycles.CreateConstIterator(); InnerIt; ++InnerIt ) { ThreadMS.Add( InnerIt.Key(), StatMetaData->ConvertCyclesToMS( InnerIt.Value() ) ); } // Pass the reference to the stats' metadata. // @TODO yrx 2014-04-03 Figure out something better later. OnAddThreadTime.ExecuteIfBound( FrameIndex, ThreadMS, StatMetaData ); //CurrentMiniViewFrame++; NumDataThisTick++; if( NumDataThisTick > MAX_NUM_DATA_PER_TICK ) { break; } } #endif // 0 return true; } void FRawProfilerSession::PrepareLoading() { SCOPE_LOG_TIME_FUNC(); const FString Filepath = DataFilepath + FStatConstants::StatsFileRawExtension; const int64 Size = IFileManager::Get().FileSize( *Filepath ); if( Size < 4 ) { UE_LOG( LogStats, Error, TEXT( "Could not open: %s" ), *Filepath ); return; } TAutoPtr FileReader( IFileManager::Get().CreateFileReader( *Filepath ) ); if( !FileReader ) { UE_LOG( LogStats, Error, TEXT( "Could not open: %s" ), *Filepath ); return; } if( !Stream.ReadHeader( *FileReader ) ) { UE_LOG( LogStats, Error, TEXT( "Could not open, bad magic: %s" ), *Filepath ); return; } const bool bIsFinalized = Stream.Header.IsFinalized(); check( bIsFinalized ); StatsThreadStats.MarkAsLoaded(); TArray Messages; if( Stream.Header.bRawStatsFile ) { // Read metadata. TArray MetadataMessages; Stream.ReadFNamesAndMetadataMessages( *FileReader, MetadataMessages ); StatsThreadStats.ProcessMetaDataOnly( MetadataMessages ); const int64 CurrentFilePos = FileReader->Tell(); // Update profiler's metadata. StatMetaData->UpdateFromStatsState( StatsThreadStats ); const uint32 GameThreadID = GetMetaData()->GetGameThreadID(); // Read frames offsets. Stream.ReadFramesOffsets( *FileReader ); // Buffer used to store the compressed and decompressed data. TArray SrcArray; TArray DestArray; const bool bHasCompressedData = Stream.Header.HasCompressedData(); // Temporary fix for setting the correct value for the SecondsPerCycle. { SCOPE_LOG_TIME( TEXT( "Looking for the STAT_SecondsPerCycle" ), nullptr ); UE_LOG( LogStats, Log, TEXT( "Looking for the STAT_SecondsPerCycle" ) ); FileReader->Seek( Stream.FramesInfo[0].FrameFileOffset ); if( bHasCompressedData ) { // Read the compressed data. FCompressedStatsData UncompressedData( SrcArray, DestArray ); *FileReader << UncompressedData; } // Select the proper archive. FMemoryReader MemoryReader( DestArray, true ); FArchive& Archive = bHasCompressedData ? MemoryReader : *FileReader; FStatPacketArray StatPacketArray; int64 TargetFrame; Archive << TargetFrame; int32 NumPackets; Archive << NumPackets; for( int32 PacketIndex = 0; PacketIndex < NumPackets; PacketIndex++ ) { FStatPacket* ToRead = new FStatPacket(); Stream.ReadStatPacket( Archive, *ToRead, bIsFinalized ); StatPacketArray.Packets.Add( ToRead ); } for( int32 PacketIndex = 0; PacketIndex < StatPacketArray.Packets.Num(); PacketIndex++ ) { const FStatPacket& StatPacket = *StatPacketArray.Packets[PacketIndex]; const TArray& Data = StatPacket.StatMessages; for( int32 Index = 0; Index < Data.Num(); Index++ ) { const FStatMessage& Item = Data[Index]; check( Item.NameAndInfo.GetFlag( EStatMetaFlags::DummyAlwaysOne ) ); // we should never be sending short names to the stats anymore const FName ShortName = Item.NameAndInfo.GetShortName(); if( ShortName == TEXT( "STAT_SecondsPerCycle" ) ) { StatMetaData->SecondsPerCycle = Item.GetValue_double(); UE_LOG( LogStats, Log, TEXT( "STAT_SecondsPerCycle is %f [ns]" ), StatMetaData->GetSecondsPerCycle()*1000*1000 ); PacketIndex = StatPacketArray.Packets.Num(); break; } } } } check( StatMetaData->GetSecondsPerCycle() > 0.0 ); { SCOPE_LOG_TIME( TEXT( "Preparing profiler frames" ), nullptr ); // Prepare profiler frames. double ElapsedTimeMS = 0; for( int32 FrameIndex = 0; FrameIndex < Stream.FramesInfo.Num(); ++FrameIndex ) { const FStatsFrameInfo& StatsFrameInfo = Stream.FramesInfo[FrameIndex]; FileReader->Seek( StatsFrameInfo.FrameFileOffset ); if( bHasCompressedData ) { // Read the compressed data. FCompressedStatsData UncompressedData( SrcArray, DestArray ); *FileReader << UncompressedData; } // Select the proper archive. FMemoryReader MemoryReader( DestArray, true ); FArchive& Archive = bHasCompressedData ? MemoryReader : *FileReader; int64 TargetFrame; Archive << TargetFrame; const double GameThreadTimeMS = GetMetaData()->ConvertCyclesToMS( (uint32)StatsFrameInfo.ThreadCycles.FindChecked( GameThreadID ) ); // Update mini-view, convert from cycles to ms. TMap ThreadTimesMS; for( auto InnerIt = StatsFrameInfo.ThreadCycles.CreateConstIterator(); InnerIt; ++InnerIt ) { const uint32 ThreadID = InnerIt.Key(); const bool bCanBeAdded = GameThreadID == ThreadID || GetMetaData()->GetRenderThreadID().Contains( ThreadID ); if( bCanBeAdded ) { ThreadTimesMS.Add( ThreadID, GetMetaData()->ConvertCyclesToMS( InnerIt.Value() ) ); } } // Pass the reference to the stats' metadata. OnAddThreadTime.ExecuteIfBound( FrameIndex, ThreadTimesMS, StatMetaData ); // Create a new profiler frame and add it to the stream. ElapsedTimeMS += GameThreadTimeMS; FProfilerFrame* ProfilerFrame = new FProfilerFrame( TargetFrame, GameThreadTimeMS, ElapsedTimeMS ); ProfilerFrame->ThreadTimesMS = ThreadTimesMS; ProfilerStream.AddProfilerFrame( TargetFrame, ProfilerFrame ); // Thread cycles can be used to generate low-res thread view. // @TODO yrx 2014-04-23 } int64 FrameOffset0 = Stream.FramesInfo[0].FrameFileOffset; FileReader->Seek( FrameOffset0 ); } #if 1 { SCOPE_LOG_TIME( TEXT( "Processing raw stats frames" ), nullptr ); double CycleCounterAdjustmentMS = 0.0f; // Read the raw stats messages. for( int32 FrameIndex = 0; FrameIndex < Stream.FramesInfo.Num(); ++FrameIndex ) { FProfilerFrame* ProfilerFrame = ProfilerStream.GetProfilerFrame( FrameIndex ); FileReader->Seek( Stream.FramesInfo[FrameIndex].FrameFileOffset ); UE_CLOG( FrameIndex % 8 == 0, LogStats, Log, TEXT( "Processing raw stats frame: %4i/%4i" ), FrameIndex, Stream.FramesInfo.Num() ); if( bHasCompressedData ) { // Read the compressed data. FCompressedStatsData UncompressedData( SrcArray, DestArray ); *FileReader << UncompressedData; } // Select the proper archive. FMemoryReader MemoryReader( DestArray, true ); FArchive& Archive = bHasCompressedData ? MemoryReader : *FileReader; FStatPacketArray Packet; int64 TargetFrame; Archive << TargetFrame; int32 NumPackets; Archive << NumPackets; for( int32 PacketIndex = 0; PacketIndex < NumPackets; PacketIndex++ ) { FStatPacket* ToRead = new FStatPacket(); Stream.ReadStatPacket( Archive, *ToRead, bIsFinalized ); Packet.Packets.Add( ToRead ); } ProcessStatPacketArray( Packet, *ProfilerFrame, FrameIndex ); // or ProfilerFrame->TargetFrame // Find the first cycle counter for the game thread. if( CycleCounterAdjustmentMS == 0.0f ) { CycleCounterAdjustmentMS = ProfilerFrame->Root->CycleCounterStartTimeMS; } TMap ThreadCycles; // Serialize thread cycles. Archive << ThreadCycles; // Update thread time and mark profiler frame as valid and ready for use. ProfilerFrame->MarkAsValid(); } // Adjust all profiler frames. ProfilerStream.AdjustCycleCounters( CycleCounterAdjustmentMS ); } #endif // 0 } const int64 AllocatedSize = ProfilerStream.GetAllocatedSize(); // We have the whole metadata and basic information about the raw stats file, start ticking the profiler session. //FTicker::GetCoreTicker().AddTicker( OnTick, 0.25f ); #if 0 if( SessionType == EProfilerSessionTypes::OfflineRaw ) { // Broadcast that a capture file has been fully processed. OnCaptureFileProcessed.ExecuteIfBound( GetInstanceID() ); } #endif // 0 } void FProfilerStatMetaData::UpdateFromStatsState( const FStatsThreadState& StatsThreadStats ) { TMap GroupFNameIDs; for( auto It = StatsThreadStats.Threads.CreateConstIterator(); It; ++It ) { ThreadDescriptions.Add( It.Key(), It.Value().ToString() ); } const uint32 NoGroupID = 0; const uint32 ThreadGroupID = 1; // Special groups. InitializeGroup( NoGroupID, "NoGroup" ); // Self must be 0. InitializeStat( 0, NoGroupID, TEXT( "Self" ), STATTYPE_CycleCounter ); // ThreadRoot must be 1. InitializeStat( 1, NoGroupID, FStatConstants::NAME_ThreadRoot.GetPlainNameString(), STATTYPE_CycleCounter, FStatConstants::NAME_ThreadRoot ); int32 UniqueID = 15; TArray GroupFNames; StatsThreadStats.Groups.MultiFind( NAME_Groups, GroupFNames ); for( const auto& GroupFName : GroupFNames ) { UniqueID++; InitializeGroup( UniqueID, GroupFName.ToString() ); GroupFNameIDs.Add( GroupFName, UniqueID ); } for( auto It = StatsThreadStats.ShortNameToLongName.CreateConstIterator(); It; ++It ) { const FStatMessage& LongName = It.Value(); const FName GroupName = LongName.NameAndInfo.GetGroupName(); if( GroupName == NAME_Groups ) { continue; } const int32 GroupID = GroupFNameIDs.FindChecked( GroupName ); const FName StatName = It.Key(); UniqueID++; EStatType StatType = STATTYPE_Error; if( LongName.NameAndInfo.GetField() == EStatDataType::ST_int64 ) { if( LongName.NameAndInfo.GetFlag( EStatMetaFlags::IsCycle ) ) { StatType = STATTYPE_CycleCounter; } else if( LongName.NameAndInfo.GetFlag( EStatMetaFlags::IsMemory ) ) { StatType = STATTYPE_MemoryCounter; } else { StatType = STATTYPE_AccumulatorDWORD; } } else if( LongName.NameAndInfo.GetField() == EStatDataType::ST_double ) { StatType = STATTYPE_AccumulatorFLOAT; } check( StatType != STATTYPE_Error ); int32 StatID = UniqueID; // Some hackery. if( StatName == TEXT( "STAT_FrameTime" ) ) { StatID = 2; } const FString Description = LongName.NameAndInfo.GetDescription(); const FString StatDesc = !Description.IsEmpty() ? Description : StatName.ToString(); InitializeStat( StatID, GroupID, StatDesc, StatType, StatName ); // Setup thread id to stat id. if( GroupName == FStatConstants::NAME_ThreadGroup ) { uint32 ThreadID = 0; for( auto ThreadsIt = StatsThreadStats.Threads.CreateConstIterator(); ThreadsIt; ++ThreadsIt ) { if (ThreadsIt.Value() == StatName) { ThreadID = ThreadsIt.Key(); break; } } ThreadIDtoStatID.Add( ThreadID, StatID ); // Game thread is always NAME_GameThread if( StatName == NAME_GameThread ) { GameThreadID = ThreadID; } // Rendering thread may be "Rendering thread" or NAME_RenderThread with an index else if( StatName.GetPlainNameString().Contains( FName(NAME_RenderThread).GetPlainNameString() ) ) { RenderThreadIDs.AddUnique( ThreadID ); } else if( StatName.GetPlainNameString().Contains( TEXT( "RenderingThread" ) ) ) { RenderThreadIDs.AddUnique( ThreadID ); } } } } void FRawProfilerSession::ProcessStatPacketArray( const FStatPacketArray& StatPacketArray, FProfilerFrame& out_ProfilerFrame, int32 FrameIndex ) { // @TODO yrx 2014-03-24 Standardize thread names and id // @TODO yrx 2014-04-22 Remove all references to the data provider, event graph etc once data graph can visualize. // Raw stats callstack for this stat packet array. TMap ThreadNodes; const FProfilerStatMetaDataRef MetaData = GetMetaData(); FProfilerSampleArray& MutableCollection = const_cast(DataProvider->GetCollection()); // Add a root sample for this frame. const uint32 FrameRootSampleIndex = DataProvider->AddHierarchicalSample( 0, MetaData->GetStatByID( 1 ).OwningGroup().ID(), 1, 0.0f, 0.0f, 1 ); // Iterate through all stats packets and raw stats messages. FName GameThreadFName = NAME_None; for( int32 PacketIndex = 0; PacketIndex < StatPacketArray.Packets.Num(); PacketIndex++ ) { const FStatPacket& StatPacket = *StatPacketArray.Packets[PacketIndex]; FName ThreadFName = StatsThreadStats.Threads.FindChecked( StatPacket.ThreadId ); const uint32 NewThreadID = MetaData->ThreadIDtoStatID.FindChecked( StatPacket.ThreadId ); // @TODO yrx 2014-04-29 Only game or render thread is supported at this moment. if( StatPacket.ThreadType != EThreadType::Game && StatPacket.ThreadType != EThreadType::Renderer ) { continue; } // Workaround for issue with rendering thread names. if( StatPacket.ThreadType == EThreadType::Renderer ) { ThreadFName = NAME_RenderThread; } FProfilerStackNode* ThreadNode = ThreadNodes.FindRef( ThreadFName ); if( !ThreadNode ) { FString ThreadIdName = FStatsUtils::BuildUniqueThreadName( StatPacket.ThreadId ); FStatMessage ThreadMessage( ThreadFName, EStatDataType::ST_int64, STAT_GROUP_TO_FStatGroup( STATGROUP_Threads )::GetGroupName(), STAT_GROUP_TO_FStatGroup( STATGROUP_Threads )::GetGroupCategory(), *ThreadIdName, true, true ); //FStatMessage ThreadMessage( ThreadFName, EStatDataType::ST_int64, nullptr, nullptr, TEXT( "" ), true, true ); ThreadMessage.NameAndInfo.SetFlag( EStatMetaFlags::IsPackedCCAndDuration, true ); ThreadMessage.Clear(); // Add a thread sample. const uint32 ThreadRootSampleIndex = DataProvider->AddHierarchicalSample ( NewThreadID, MetaData->GetStatByID( NewThreadID ).OwningGroup().ID(), NewThreadID, -1.0f, -1.0f, 1, FrameRootSampleIndex ); ThreadNode = ThreadNodes.Add( ThreadFName, new FProfilerStackNode( nullptr, ThreadMessage, ThreadRootSampleIndex, FrameIndex ) ); } if( StatPacket.ThreadType == EThreadType::Game ) { GameThreadFName = ThreadFName; } TArray StartStack; TArray Stack; Stack.Add( ThreadNode ); FProfilerStackNode* Current = Stack.Last(); const TArray& Data = StatPacket.StatMessages; for( int32 Index = 0; Index < Data.Num(); Index++ ) { const FStatMessage& Item = Data[Index]; check( Item.NameAndInfo.GetFlag( EStatMetaFlags::DummyAlwaysOne ) ); // we should never be sending short names to the stats anymore const EStatOperation::Type Op = Item.NameAndInfo.GetField(); const FName LongName = Item.NameAndInfo.GetRawName(); const FName ShortName = Item.NameAndInfo.GetShortName(); if( Op == EStatOperation::CycleScopeStart || Op == EStatOperation::CycleScopeEnd ) { check( Item.NameAndInfo.GetFlag( EStatMetaFlags::IsCycle ) ); if( Op == EStatOperation::CycleScopeStart ) { FProfilerStackNode* ChildNode = new FProfilerStackNode( Current, Item, -1, FrameIndex ); Current->Children.Add( ChildNode ); // Add a child sample. const uint32 SampleIndex = DataProvider->AddHierarchicalSample ( NewThreadID, MetaData->GetStatByFName( ShortName ).OwningGroup().ID(), // GroupID MetaData->GetStatByFName( ShortName ).ID(), // StatID MetaData->ConvertCyclesToMS( ChildNode->CyclesStart ), // StartMS MetaData->ConvertCyclesToMS( 0 ), // DurationMS 1, Current->SampleIndex ); ChildNode->SampleIndex = SampleIndex; Stack.Add( ChildNode ); StartStack.Add( &Item ); Current = ChildNode; } if( Op == EStatOperation::CycleScopeEnd ) { const FStatMessage ScopeStart = *StartStack.Pop(); const FStatMessage ScopeEnd = Item; const int64 Delta = int32( uint32( ScopeEnd.GetValue_int64() ) - uint32( ScopeStart.GetValue_int64() ) ); Current->CyclesEnd = Current->CyclesStart + int64( Delta ); Current->CycleCounterStartTimeMS = MetaData->ConvertCyclesToMS( Current->CyclesStart ); Current->CycleCounterEndTimeMS = MetaData->ConvertCyclesToMS( Current->CyclesEnd ); check( Current->CycleCounterEndTimeMS >= Current->CycleCounterStartTimeMS ); FProfilerStackNode* ChildNode = Current; // Update the child sample's DurationMS. MutableCollection[ChildNode->SampleIndex].SetDurationMS( MetaData->ConvertCyclesToMS( Delta ) ); verify( Current == Stack.Pop() ); Current = Stack.Last(); } } } } // Calculate thread times. for( auto It = ThreadNodes.CreateIterator(); It; ++It ) { FProfilerStackNode& ThreadNode = *It.Value(); const int32 ChildrenNum = ThreadNode.Children.Num(); if( ChildrenNum > 0 ) { const int32 LastChildIndex = ThreadNode.Children.Num() - 1; ThreadNode.CyclesStart = ThreadNode.Children[0]->CyclesStart; ThreadNode.CyclesEnd = ThreadNode.Children[LastChildIndex]->CyclesEnd; ThreadNode.CycleCounterStartTimeMS = MetaData->ConvertCyclesToMS( ThreadNode.CyclesStart ); ThreadNode.CycleCounterEndTimeMS = MetaData->ConvertCyclesToMS( ThreadNode.CyclesEnd ); FProfilerSample& ProfilerSample = MutableCollection[ThreadNode.SampleIndex]; ProfilerSample.SetStartAndEndMS( MetaData->ConvertCyclesToMS( ThreadNode.CyclesStart ), MetaData->ConvertCyclesToMS( ThreadNode.CyclesEnd ) ); } } // Get the game thread time. check( GameThreadFName != NAME_None ); const FProfilerStackNode& GameThreadNode = *ThreadNodes.FindChecked( GameThreadFName ); const double GameThreadStartMS = MetaData->ConvertCyclesToMS( GameThreadNode.CyclesStart ); const double GameThreadEndMS = MetaData->ConvertCyclesToMS( GameThreadNode.CyclesEnd ); MutableCollection[FrameRootSampleIndex].SetStartAndEndMS( GameThreadStartMS, GameThreadEndMS ); // Advance frame const uint32 LastFrameIndex = DataProvider->GetNumFrames(); DataProvider->AdvanceFrame( GameThreadEndMS - GameThreadStartMS ); // Update aggregated stats UpdateAggregatedStats( LastFrameIndex ); // Update aggregated events. UpdateAggregatedEventGraphData( LastFrameIndex ); // RootNode is the same as the game thread node. out_ProfilerFrame.Root->CycleCounterStartTimeMS = GameThreadStartMS; out_ProfilerFrame.Root->CycleCounterEndTimeMS = GameThreadEndMS; for( auto It = ThreadNodes.CreateIterator(); It; ++It ) { out_ProfilerFrame.AddChild( It.Value() ); } out_ProfilerFrame.SortChildren(); }