runtime_statistics.f90

Go to the documentation of this file.

! Copyright (c) 2024, The Neko Authors
! All rights reserved.
!
! Redistribution and use in source and binary forms, with or without
! modification, are permitted provided that the following conditions
! are met:
!
!   * Redistributions of source code must retain the above copyright
!     notice, this list of conditions and the following disclaimer.
!
!   * Redistributions in binary form must reproduce the above
!     copyright notice, this list of conditions and the following
!     disclaimer in the documentation and/or other materials provided
!     with the distribution.
!
!   * Neither the name of the authors nor the names of its
!     contributors may be used to endorse or promote products derived
!     from this software without specific prior written permission.
!
! THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS
! "AS IS" AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT
! LIMITED TO, THE IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS
! FOR A PARTICULAR PURPOSE ARE DISCLAIMED. IN NO EVENT SHALL THE
! COPYRIGHT OWNER OR CONTRIBUTORS BE LIABLE FOR ANY DIRECT, INDIRECT,
! INCIDENTAL, SPECIAL, EXEMPLARY, OR CONSEQUENTIAL DAMAGES (INCLUDING,
! BUT NOT LIMITED TO, PROCUREMENT OF SUBSTITUTE GOODS OR SERVICES;
! LOSS OF USE, DATA, OR PROFITS; OR BUSINESS INTERRUPTION) HOWEVER
! CAUSED AND ON ANY THEORY OF LIABILITY, WHETHER IN CONTRACT, STRICT
! LIABILITY, OR TORT (INCLUDING NEGLIGENCE OR OTHERWISE) ARISING IN
! ANY WAY OUT OF THE USE OF THIS SOFTWARE, EVEN IF ADVISED OF THE
! POSSIBILITY OF SUCH DAMAGE.
!
module runtime_stats
  use logger, only : neko_log, log_size
  use stack, only : stack_r8_t, stack_i4r8t2_t
  use tuple, only : tuple_i4r8_t
  use num_types, only : dp
  use json_utils, only : json_get, json_get_or_default
  use json_module, only : json_file
  use file, only : file_t
  use matrix, only : matrix_t
  use comm
  use mpi_f08
  implicit none
  private
 
  integer :: rt_stats_max_regions = 50
  
  type :: runtime_stats_t
     character(len=19), allocatable :: rt_stats_id(:)
     type(stack_r8_t), allocatable :: elapsed_time_(:)
     type(stack_i4r8t2_t) :: region_timestamp_
     logical :: enabled_
     logical :: output_profile_
    contains
     procedure, pass(this) :: init => runtime_stats_init
     procedure, pass(this) :: free => runtime_stats_free
     procedure, pass(this) :: start_region => runtime_stats_start_region
     procedure, pass(this) :: end_region => runtime_stats_end_region
     procedure, pass(this) :: report => runtime_stats_report
  end type runtime_stats_t
 
  type(runtime_stats_t), public :: neko_rt_stats
 
contains
 
  subroutine runtime_stats_init(this, params)
    class(runtime_stats_t), intent(inout) :: this
    type(json_file), intent(inout) :: params
    integer :: i
    
    call this%free()
 
    call json_get_or_default(params, 'case.runtime_statistics.enabled', &
         this%enabled_, .false.)
    call json_get_or_default(params, &
         'case.runtime_statistics.output_profile', &
         this%output_profile_, .false.)
 
    if (this%enabled_) then
 
       allocate(this%rt_stats_id(rt_stats_max_regions))
       
       this%rt_stats_id = ''
       
       allocate(this%elapsed_time_(rt_stats_max_regions))
       do i = 1, rt_stats_max_regions
          call this%elapsed_time_(i)%init()
       end do
       
       call this%region_timestamp_%init(100)
 
    end if
    
  end subroutine runtime_stats_init
 
  subroutine runtime_stats_free(this)
    class(runtime_stats_t), intent(inout) :: this
    integer :: i
    
    if (allocated(this%rt_stats_id)) then
       deallocate(this%rt_stats_id)
    end if
 
    if (allocated(this%elapsed_time_)) then
       do i = 1, size(this%elapsed_time_)
          call this%elapsed_time_(i)%free()
       end do
       deallocate(this%elapsed_time_)
    end if
 
    call this%region_timestamp_%free()
    
  end subroutine runtime_stats_free
 
  subroutine runtime_stats_start_region(this, name, region_id)
    class(runtime_stats_t), intent(inout) :: this
    character(len=*) :: name
    integer, intent(in) :: region_id
    type(tuple_i4r8_t) :: region_data
 
    if (.not. this%enabled_) then
       return
    end if
    
    if (region_id .gt. 0 .and. region_id .le. rt_stats_max_regions) then
       if (len_trim(this%rt_stats_id(region_id)) .eq. 0) then
          this%rt_stats_id(region_id) = trim(name)
       else
          if (trim(this%rt_stats_id(region_id)) .ne. trim(name)) then
             call neko_error('Profile region renamed')
          end if
       end if
       region_data%x = region_id
       region_data%y = mpi_wtime()
       call this%region_timestamp_%push(region_data)
    else
       call neko_error('Invalid profiling region id')
    end if
    
  end subroutine runtime_stats_start_region
 
  subroutine runtime_stats_end_region(this, name, region_id)
    class(runtime_stats_t), intent(inout) :: this
    character(len=*) :: name
    integer, intent(in) :: region_id
    real(kind=dp) :: end_time, elapsed_time
    type(tuple_i4r8_t) :: region_data
 
    if (.not. this%enabled_) then
       return
    end if
 
    end_time = mpi_wtime()
 
    if (trim(this%rt_stats_id(region_id)) .ne. trim(name)) then
       call neko_error('Invalid profiler region closed (' // name // ', &
            &expected: ' // trim(this%rt_stats_id(region_id)) // ')')
    end if
    region_data = this%region_timestamp_%pop()
    
    if (region_data%x .gt. 0) then
       elapsed_time = end_time - region_data%y
       call this%elapsed_time_(region_data%x)%push(elapsed_time)
    end if
 
  end subroutine runtime_stats_end_region
 
  subroutine runtime_stats_report(this)
    class(runtime_stats_t), intent(inout) :: this
    character(len=LOG_SIZE) :: log_buf
    character(len=1250) :: hdr
    real(kind=dp) :: avg, std, sem, total
    integer :: i, nsamples, ncols, nrows, col_idx
    type(matrix_t) :: profile_data
 
    if (.not. this%enabled_) then
       return
    end if
    
    call neko_log%section('Runtime statistics')
    call neko_log%newline()
    write(log_buf, '(A,A,1x,A,1x,A)') '                  ',&
         '      Total time  ','     Avg. time ','     Range  +/-'
    call neko_log%message(log_buf)
    write(log_buf, '(A)') &
         '--------------------------------------------------------------------'
    call neko_log%message(log_buf)
 
    ncols = 0
    nrows = 0
    hdr = ''
    do i = 1, size(this%elapsed_time_)
       if (len_trim(this%rt_stats_id(i)) .gt. 0) then
          nsamples = this%elapsed_time_(i)%size()
          ncols = ncols + 1          
          hdr = trim(hdr) // trim(this%rt_stats_id(i)) // ', '
          nrows = max(nrows, nsamples)             
          if (nsamples .gt. 0) then
             select type (region_sample => this%elapsed_time_(i)%data)
             type is (double precision)
                total = sum(region_sample(1:nsamples))
                call mpi_allreduce(mpi_in_place, total, 1, &
                     mpi_double_precision, mpi_sum, neko_comm)
                total = total / pe_size
                avg = total / nsamples
                std = (total - avg)**2 / nsamples
                sem = std /sqrt(real(nsamples, dp))
             end select
             write(log_buf, '(A, E15.7,1x,1x,E15.7,1x,1x,E15.7)')  &
                  this%rt_stats_id(i), total, avg, 2.5758_dp * sem
             call neko_log%message(log_buf)
          end if
       end if
    end do
 
    call neko_log%newline()
 
    if (this%output_profile_) then
       col_idx = 0
       call profile_data%init(nrows, ncols)
       do i = 1, size(this%elapsed_time_)
          if (len_trim(this%rt_stats_id(i)) .gt. 0) then
             nsamples = this%elapsed_time_(i)%size()
             col_idx = col_idx + 1             
             if (nsamples .gt. 0) then
                select type (region_sample => this%elapsed_time_(i)%data)
                type is (double precision)
                   profile_data%x(1:nsamples,col_idx) = &
                        region_sample(1:nsamples)
                   call mpi_allreduce(mpi_in_place, &
                        profile_data%x(1:nsamples,col_idx), nsamples, &
                        mpi_double_precision, mpi_sum, neko_comm)
                   profile_data%x(1:nsamples, col_idx) = &
                        profile_data%x(1:nsamples, col_idx) / pe_size
                end select
             end if
          end if
       end do
       
       if (pe_rank .eq. 0) then
          block
            type(file_t) :: profile_file    
            profile_file = file_t('profile.csv')
            call profile_file%set_header(hdr)
            call profile_file%write(profile_data)
          end block
       end if
    end if
    call neko_log%end_section()
 
    call profile_data%free()
 
  end subroutine runtime_stats_report
  
end module runtime_stats