MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.09-1-gfb0b31e1439b) initialized
Starting run at 05:39:45 UTC on 2022-09-06.
Successfully read inputs file ...
Castro git describe: 22.09
AMReX git describe: 22.09-1-gfb0b31e14
Microphysics git describe: 22.08-10-g65622313
reading extern runtime parameters ...
3 Species:
C12 O16 Mg24
Successfully read inputs file ...
INITIAL GRIDS
Level 0 8 grids 262144 cells 100 % of domain
smallest grid: 32 x 32 x 32 biggest grid: 32 x 32 x 32
PLOTFILE: file = dustcollapse-3d-monopole_plt00000
Write plotfile time = 0.0158316 seconds
[Level 0 step 1] ADVANCE with dt = 4.541742215e-05
[Level 0 step 1] Advanced 262144 cells
[STEP 1] Coarse TimeStep time: 0.360826497
[STEP 1] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 1 TIME = 4.541742215e-05 DT = 4.541742215e-05
[Level 0 step 2] ADVANCE with dt = 4.768829326e-05
[Level 0 step 2] Advanced 262144 cells
[STEP 2] Coarse TimeStep time: 0.346373253
[STEP 2] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 2 TIME = 9.31057154e-05 DT = 4.768829326e-05
[Level 0 step 3] ADVANCE with dt = 5.007270792e-05
[Level 0 step 3] Advanced 262144 cells
[STEP 3] Coarse TimeStep time: 0.342266061
[STEP 3] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 3 TIME = 0.0001431784233 DT = 5.007270792e-05
[Level 0 step 4] ADVANCE with dt = 5.257634331e-05
[Level 0 step 4] Advanced 262144 cells
[STEP 4] Coarse TimeStep time: 0.344325174
[STEP 4] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 4 TIME = 0.0001957547666 DT = 5.257634331e-05
[Level 0 step 5] ADVANCE with dt = 5.520516048e-05
[Level 0 step 5] Advanced 262144 cells
[STEP 5] Coarse TimeStep time: 0.356661844
[STEP 5] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 5 TIME = 0.0002509599271 DT = 5.520516048e-05
[Level 0 step 6] ADVANCE with dt = 5.79654185e-05
[Level 0 step 6] Advanced 262144 cells
[STEP 6] Coarse TimeStep time: 0.353274213
[STEP 6] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 6 TIME = 0.0003089253456 DT = 5.79654185e-05
[Level 0 step 7] ADVANCE with dt = 6.086368943e-05
[Level 0 step 7] Advanced 262144 cells
[STEP 7] Coarse TimeStep time: 0.340700784
[STEP 7] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 7 TIME = 0.000369789035 DT = 6.086368943e-05
[Level 0 step 8] ADVANCE with dt = 6.39068739e-05
[Level 0 step 8] Advanced 262144 cells
[STEP 8] Coarse TimeStep time: 0.339173511
[STEP 8] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 8 TIME = 0.000433695909 DT = 6.39068739e-05
[Level 0 step 9] ADVANCE with dt = 6.71022176e-05
[Level 0 step 9] Advanced 262144 cells
[STEP 9] Coarse TimeStep time: 0.340056346
[STEP 9] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 9 TIME = 0.0005007981265 DT = 6.71022176e-05
[Level 0 step 10] ADVANCE with dt = 7.045732848e-05
[Level 0 step 10] Advanced 262144 cells
[STEP 10] Coarse TimeStep time: 0.343682367
[STEP 10] FAB kilobyte spread across MPI nodes: [57998 ... 57998]
STEP = 10 TIME = 0.000571255455 DT = 7.045732848e-05
PLOTFILE: file = dustcollapse-3d-monopole_plt00010
Write plotfile time = 0.010470844 seconds
Ending run at 05:39:49 UTC on 2022-09-06.
Run time = 3.669411302
Run time without initialization = 3.485525854
Average number of zones advanced per microsecond: 0.752
Average number of zones advanced per microsecond per rank: 0.094
TinyProfiler total time across processes [min...avg...max]: 3.667 ... 3.669 ... 3.669
-------------------------------------------------------------------------------------------
Name NCalls Excl. Min Excl. Avg Excl. Max Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source() 10 2.1453 2.4183 2.5295 68.93%
Castro::do_advance_ctu() 10 0.03604 0.1486 0.4239 11.55%
Gravity::make_radial_gravity() 21 0.3443 0.3561 0.3796 10.34%
FabArray::ParallelCopy_finish() 41 0.04535 0.06316 0.1199 3.27%
Castro::initData() 1 0.03946 0.07827 0.1162 3.17%
Castro::estTimeStep() 21 0.02796 0.067 0.1034 2.82%
FabArray::setVal() 143 0.06214 0.07036 0.07256 1.98%
Castro::reset_internal_energy(Fab) 1008 0.04724 0.05048 0.05279 1.44%
FabArray::ParallelCopy_nowait() 41 0.02762 0.04466 0.05047 1.38%
StateData::FillBoundary(geom) 41 0.03793 0.04249 0.0462 1.26%
Castro::normalize_species() 62 0.04124 0.0421 0.04508 1.23%
FillPatchIterator::Initialize 41 0.02473 0.03578 0.03997 1.09%
Gravity::interpolate_monopole_grav() 21 0.02678 0.02751 0.02783 0.76%
Castro::construct_new_gravity_source() 10 0.02012 0.02281 0.02384 0.65%
Castro::expand_state() 10 0.009892 0.01834 0.02194 0.60%
Castro::computeTemp() 63 0.018 0.01925 0.02017 0.55%
Castro::initialize_do_advance() 10 0.01022 0.01743 0.01977 0.54%
MultiFab::Saxpy() 20 0.008686 0.0143 0.01626 0.44%
VisMF::Write(FabArray) 2 0.01262 0.01329 0.01611 0.44%
Amr::writePlotFile() 2 0.01039 0.01304 0.01375 0.37%
Gravity::get_new_grav_vector() 11 0.007471 0.01137 0.01274 0.35%
FabArray::setDomainBndry() 41 0.004798 0.01047 0.01243 0.34%
MultiFab::contains_nan() 20 0.009451 0.01099 0.01194 0.33%
Castro::construct_old_gravity_source() 10 0.004863 0.009976 0.01154 0.31%
Gravity::get_old_grav_vector() 10 0.005554 0.009313 0.01077 0.29%
Castro::initialize_advance() 10 0.006589 0.009886 0.01065 0.29%
Castro::construct_new_source() 50 0.004863 0.006515 0.007202 0.20%
main() 1 0.004124 0.006355 0.00702 0.19%
Castro::initMFs() 1 0.00676 0.006773 0.006782 0.18%
Castro::enforce_min_density() 62 0.005313 0.006095 0.006714 0.18%
StateData::define() 4 0.005569 0.005592 0.005613 0.15%
Castro::swap_state_time_levels() 10 0.00328 0.004374 0.004624 0.13%
Amr::coarseTimeStep() 10 0.0007669 0.002323 0.003987 0.11%
Castro::finalize_do_advance() 10 0.0006854 0.001423 0.001622 0.04%
Castro::buildMetrics() 1 0.001601 0.001604 0.001609 0.04%
Amr::timeStep() 10 0.0001777 0.0002332 0.0004608 0.01%
Amr::InitAmr() 1 0.0002696 0.0002957 0.0004284 0.01%
Castro::reset_internal_energy(MultiFab) 63 0.0002756 0.0002945 0.0003219 0.01%
Castro::clean_state() 62 0.000153 0.0001757 0.0001915 0.01%
DistributionMapping::LeastUsedCPUs() 1 9.601e-06 0.0001407 0.0001612 0.00%
StateDataPhysBCFunct::() 41 9.991e-05 0.0001269 0.0001437 0.00%
FabArray::ParallelCopy() 41 6.332e-05 8.748e-05 9.952e-05 0.00%
Castro::enforce_consistent_e() 1 7.596e-05 8.646e-05 9.862e-05 0.00%
Castro::Castro() 1 6.335e-05 7.195e-05 9.459e-05 0.00%
Castro::construct_old_source() 50 2.849e-05 7.029e-05 8.511e-05 0.00%
Castro::do_old_sources() 10 4.097e-05 6.805e-05 8.415e-05 0.00%
Castro::do_new_sources() 10 3.96e-05 6.738e-05 7.935e-05 0.00%
FillPatchSingleLevel 41 5.752e-05 6.504e-05 7.383e-05 0.00%
Amr::FinalizeInit() 1 3.935e-06 1.305e-05 7.298e-05 0.00%
Amr::writeSmallPlotFile() 1 3.212e-05 6.192e-05 7.007e-05 0.00%
FabArrayBase::getCPC() 41 4.86e-05 5.609e-05 6.976e-05 0.00%
Castro::construct_old_gravity() 10 3.334e-05 5.051e-05 6.266e-05 0.00%
Castro::finalize_advance() 10 4.827e-05 5.324e-05 6.119e-05 0.00%
Amr::defBaseLevel() 1 3.667e-05 4.785e-05 6.117e-05 0.00%
Castro::construct_new_gravity() 10 3.488e-05 4.618e-05 5.789e-05 0.00%
Castro::subcycle_advance_ctu() 10 2.772e-05 5.082e-05 5.697e-05 0.00%
Castro::apply_source_to_state() 20 4.163e-05 4.931e-05 5.679e-05 0.00%
Castro::advance() 10 2.968e-05 4.262e-05 4.752e-05 0.00%
Castro::enforce_speed_limit() 62 2.063e-05 2.745e-05 3.378e-05 0.00%
Castro::check_for_nan() 20 1.827e-05 2.342e-05 2.828e-05 0.00%
FabArrayBase::CPC::define() 2 2.296e-05 2.516e-05 2.66e-05 0.00%
Castro::post_timestep() 10 1.509e-05 1.781e-05 2.321e-05 0.00%
AmrLevel::AmrLevel(dm) 1 7.1e-06 1.127e-05 1.546e-05 0.00%
Gravity::swapTimeLevels() 10 7.049e-06 1.249e-05 1.542e-05 0.00%
Castro::create_source_corrector() 10 6.206e-06 1.045e-05 1.433e-05 0.00%
Gravity::set_mass_offset() 10 5.85e-06 9.556e-06 1.236e-05 0.00%
Castro::computeNewDt() 9 8.278e-06 1.022e-05 1.128e-05 0.00%
Castro::FluxRegCrseInit 10 6.726e-06 8.503e-06 1.005e-05 0.00%
Amr::initSubcycle() 1 8.166e-06 8.514e-06 9.404e-06 0.00%
Amr::InitializeInit() 1 2.254e-06 4.099e-06 8.644e-06 0.00%
Castro::retry_advance_ctu() 10 4.465e-06 6.066e-06 7.563e-06 0.00%
DistributionMapping::SFCProcessorMapDoIt() 1 3.6e-06 4.312e-06 6.546e-06 0.00%
Castro::computeInitialDt() 2 3.128e-06 3.841e-06 4.303e-06 0.00%
Castro::post_regrid() 1 2.061e-06 2.749e-06 4.216e-06 0.00%
Castro::post_init() 1 2.915e-06 3.443e-06 3.834e-06 0.00%
Castro::FluxRegFineAdd() 10 1.893e-06 2.292e-06 2.659e-06 0.00%
Amr::init() 1 1.577e-06 2.06e-06 2.639e-06 0.00%
Amr::initialInit() 1 1.209e-06 1.629e-06 2.084e-06 0.00%
DistributionMapping::Distribute() 1 9.3e-07 1.072e-06 1.295e-06 0.00%
-------------------------------------------------------------------------------------------
-------------------------------------------------------------------------------------------
Name NCalls Incl. Min Incl. Avg Incl. Max Max %
-------------------------------------------------------------------------------------------
main() 1 3.667 3.669 3.669 100.00%
Amr::coarseTimeStep() 10 3.468 3.468 3.468 94.51%
Amr::timeStep() 10 3.456 3.458 3.459 94.27%
Castro::advance() 10 3.441 3.442 3.442 93.79%
Castro::subcycle_advance_ctu() 10 3.368 3.37 3.376 92.01%
Castro::do_advance_ctu() 10 3.368 3.37 3.376 92.01%
Castro::construct_ctu_hydro_source() 10 2.145 2.418 2.529 68.93%
Gravity::make_radial_gravity() 21 0.3443 0.3561 0.3796 10.34%
Gravity::get_new_grav_vector() 11 0.227 0.2327 0.235 6.40%
Castro::construct_old_gravity() 10 0.2027 0.2129 0.2298 6.26%
Gravity::get_old_grav_vector() 10 0.2019 0.2121 0.2293 6.25%
FillPatchIterator::Initialize 41 0.1879 0.1969 0.216 5.89%
Castro::construct_new_gravity() 10 0.2014 0.206 0.2079 5.67%
Castro::initialize_do_advance() 10 0.1675 0.1804 0.1877 5.11%
FillPatchSingleLevel 41 0.1367 0.1507 0.1865 5.08%
Amr::init() 1 0.1833 0.1835 0.1835 5.00%
Amr::initialInit() 1 0.1674 0.1677 0.1679 4.58%
FabArray::ParallelCopy() 41 0.0939 0.108 0.1477 4.03%
Castro::expand_state() 10 0.1227 0.1324 0.1381 3.76%
Amr::InitializeInit() 1 0.06108 0.09979 0.1378 3.76%
Amr::defBaseLevel() 1 0.06108 0.09978 0.1378 3.76%
Castro::clean_state() 62 0.1153 0.1175 0.1224 3.34%
Castro::initData() 1 0.0436 0.08236 0.1204 3.28%
FabArray::ParallelCopy_finish() 41 0.04535 0.06316 0.1199 3.27%
Amr::FinalizeInit() 1 0.02984 0.06788 0.1068 2.91%
Castro::estTimeStep() 21 0.02796 0.067 0.1034 2.82%
Castro::computeInitialDt() 2 0.001719 0.03972 0.07844 2.14%
Castro::initialize_advance() 10 0.06456 0.07138 0.07386 2.01%
Castro::computeTemp() 63 0.06554 0.07003 0.07328 2.00%
FabArray::setVal() 143 0.06214 0.07036 0.07256 1.98%
Castro::do_new_sources() 10 0.05595 0.06319 0.06472 1.76%
Castro::reset_internal_energy(MultiFab) 63 0.04753 0.05077 0.05311 1.45%
Castro::reset_internal_energy(Fab) 1008 0.04724 0.05048 0.05279 1.44%
FabArray::ParallelCopy_nowait() 41 0.0277 0.04474 0.05055 1.38%
Castro::do_old_sources() 10 0.03529 0.04545 0.04942 1.35%
StateDataPhysBCFunct::() 41 0.03803 0.04262 0.04634 1.26%
StateData::FillBoundary(geom) 41 0.03793 0.04249 0.0462 1.26%
Castro::normalize_species() 62 0.04124 0.0421 0.04508 1.23%
Castro::construct_new_source() 50 0.02498 0.02932 0.03023 0.82%
Gravity::interpolate_monopole_grav() 21 0.02678 0.02751 0.02783 0.76%
Castro::post_init() 1 0.02717 0.02749 0.02775 0.76%
Amr::writePlotFile() 2 0.02611 0.02634 0.02661 0.73%
Castro::construct_new_gravity_source() 10 0.02012 0.02281 0.02384 0.65%
Castro::post_timestep() 10 0.015 0.01593 0.01732 0.47%
Castro::apply_source_to_state() 20 0.00873 0.01435 0.01631 0.44%
MultiFab::Saxpy() 20 0.008686 0.0143 0.01626 0.44%
VisMF::Write(FabArray) 2 0.01262 0.01329 0.01611 0.44%
FabArray::setDomainBndry() 41 0.004798 0.01047 0.01243 0.34%
Castro::check_for_nan() 20 0.009472 0.01101 0.01197 0.33%
MultiFab::contains_nan() 20 0.009451 0.01099 0.01194 0.33%
Castro::Castro() 1 0.01153 0.01162 0.01166 0.32%
Castro::construct_old_source() 50 0.004891 0.01005 0.01161 0.32%
Castro::construct_old_gravity_source() 10 0.004863 0.009976 0.01154 0.31%
Castro::computeNewDt() 9 0.007067 0.007077 0.007084 0.19%
Castro::initMFs() 1 0.00676 0.006773 0.006782 0.18%
Castro::enforce_min_density() 62 0.005313 0.006095 0.006714 0.18%
AmrLevel::AmrLevel(dm) 1 0.005585 0.005604 0.005621 0.15%
StateData::define() 4 0.005569 0.005592 0.005613 0.15%
Castro::swap_state_time_levels() 10 0.00328 0.004374 0.004624 0.13%
Castro::buildMetrics() 1 0.001733 0.001744 0.00175 0.05%
Castro::post_regrid() 1 0.001525 0.001585 0.001655 0.05%
Castro::finalize_do_advance() 10 0.0006854 0.001423 0.001622 0.04%
Amr::InitAmr() 1 0.000279 0.0003042 0.000437 0.01%
DistributionMapping::SFCProcessorMapDoIt() 1 1.413e-05 0.000146 0.000169 0.00%
DistributionMapping::LeastUsedCPUs() 1 9.601e-06 0.0001407 0.0001612 0.00%
Castro::enforce_consistent_e() 1 7.596e-05 8.646e-05 9.862e-05 0.00%
FabArrayBase::getCPC() 41 7.36e-05 8.125e-05 9.525e-05 0.00%
Castro::finalize_advance() 10 5.788e-05 6.403e-05 7.342e-05 0.00%
Amr::writeSmallPlotFile() 1 3.212e-05 6.192e-05 7.007e-05 0.00%
Castro::enforce_speed_limit() 62 2.063e-05 2.745e-05 3.378e-05 0.00%
FabArrayBase::CPC::define() 2 2.296e-05 2.516e-05 2.66e-05 0.00%
Gravity::swapTimeLevels() 10 7.049e-06 1.249e-05 1.542e-05 0.00%
Castro::create_source_corrector() 10 6.206e-06 1.045e-05 1.433e-05 0.00%
Gravity::set_mass_offset() 10 5.85e-06 9.556e-06 1.236e-05 0.00%
Castro::FluxRegCrseInit 10 6.726e-06 8.503e-06 1.005e-05 0.00%
Amr::initSubcycle() 1 8.166e-06 8.514e-06 9.404e-06 0.00%
Castro::retry_advance_ctu() 10 4.465e-06 6.066e-06 7.563e-06 0.00%
Castro::FluxRegFineAdd() 10 1.893e-06 2.292e-06 2.659e-06 0.00%
DistributionMapping::Distribute() 1 9.3e-07 1.072e-06 1.295e-06 0.00%
-------------------------------------------------------------------------------------------
Unused ParmParse Variables:
[TOP]::amr.ref_ratio(nvals = 4) :: [2, 2, 2, 2]
[TOP]::amr.regrid_int(nvals = 4) :: [2, 2, 2, 2]
AMReX (22.09-1-gfb0b31e1439b) finalized