MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.07-5-g557aae84902f) initialized

Starting run at 05:38:11 UTC on 2022-07-07.
Successfully read inputs file ... 

Castro git describe: 22.07-1-g2377b4709
AMReX git describe: 22.07-5-g557aae849
Microphysics git describe: 22.07-6-g94202288

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.013947329  seconds

[Level 0 step 1] ADVANCE with dt = 4.541742215e-05
[Level 0 step 1] Advanced 262144 cells

[STEP 1] Coarse TimeStep time: 0.357519612
[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.339623826
[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.33942237
[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.338314832
[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.350366489
[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.346507448
[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.335578133
[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.336828374
[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.335055508
[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.338271821
[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.009462427  seconds


Ending run at 05:38:14 UTC on 2022-07-07.
Run time = 3.61592357
Run time without initialization = 3.433041216

  Average number of zones advanced per microsecond: 0.764
  Average number of zones advanced per microsecond per rank: 0.095



TinyProfiler total time across processes [min...avg...max]: 3.614 ... 3.616 ... 3.616

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1153     2.3913     2.4894  68.84%
Castro::do_advance_ctu()                        10    0.03467      0.135     0.4137  11.44%
Gravity::make_radial_gravity()                  21     0.3435      0.358     0.3821  10.57%
Castro::initData()                               1    0.03853    0.08096     0.1183   3.27%
FabArray::ParallelCopy_finish()                 41    0.03946    0.05924     0.1181   3.27%
Castro::estTimeStep()                           21     0.0275    0.06748     0.1085   3.00%
FabArray::setVal()                             143    0.05941    0.06849    0.07123   1.97%
Castro::reset_internal_energy(Fab)            1008    0.04753    0.05068    0.05275   1.46%
FabArray::ParallelCopy_nowait()                 41    0.02633    0.04316    0.04881   1.35%
StateData::FillBoundary(geom)                   41    0.03459    0.04201    0.04587   1.27%
Castro::normalize_species()                     62    0.04144    0.04181    0.04269   1.18%
FillPatchIterator::Initialize                   41    0.02138    0.03318    0.03764   1.04%
Gravity::interpolate_monopole_grav()            21    0.02694    0.02769    0.02824   0.78%
Castro::construct_new_gravity_source()          10    0.01953    0.02303     0.0243   0.67%
Castro::expand_state()                          10   0.009737    0.01845     0.0218   0.60%
Castro::computeTemp()                           63    0.01816    0.01937    0.02038   0.56%
Castro::initialize_do_advance()                 10   0.009194    0.01655    0.01847   0.51%
MultiFab::Saxpy()                               20   0.008581    0.01422    0.01608   0.44%
VisMF::Write(FabArray)                           2    0.01125    0.01196     0.0156   0.43%
Gravity::get_new_grav_vector()                  11   0.007159    0.01112    0.01284   0.36%
MultiFab::contains_nan()                        20   0.009345    0.01092    0.01239   0.34%
Amr::writePlotFile()                             2   0.008027    0.01138    0.01238   0.34%
FabArray::setDomainBndry()                      41   0.004899    0.01036    0.01232   0.34%
Castro::construct_old_gravity_source()          10   0.004915    0.01005    0.01197   0.33%
Gravity::get_old_grav_vector()                  10   0.005634   0.009348     0.0109   0.30%
Castro::initialize_advance()                    10   0.006273   0.009758    0.01053   0.29%
Castro::enforce_min_density()                   62   0.006137    0.00676   0.007261   0.20%
Castro::initMFs()                                1    0.00555   0.006028    0.00713   0.20%
Castro::construct_new_source()                  50   0.004861    0.00645   0.007031   0.19%
StateData::define()                              4   0.004579   0.005236   0.006813   0.19%
main()                                           1   0.003323   0.004948   0.005344   0.15%
Castro::swap_state_time_levels()                10   0.002878   0.003727   0.004302   0.12%
Amr::coarseTimeStep()                           10   0.001024   0.001702   0.002444   0.07%
Castro::buildMetrics()                           1   0.001328   0.001412   0.001474   0.04%
Castro::finalize_do_advance()                   10  0.0005254    0.00109   0.001259   0.03%
Amr::InitAmr()                                   1   0.000265  0.0003618  0.0005199   0.01%
Amr::timeStep()                                 10  0.0001763  0.0002338  0.0004931   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.341e-06  0.0002199  0.0003446   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0003024  0.0003104  0.0003182   0.01%
Castro::clean_state()                           62   0.000149  0.0001575  0.0001771   0.00%
StateDataPhysBCFunct::()                        41  0.0001157  0.0001327  0.0001564   0.00%
Castro::do_new_sources()                        10   4.44e-05  7.418e-05  9.819e-05   0.00%
FabArray::ParallelCopy()                        41  7.292e-05  8.832e-05  9.725e-05   0.00%
Castro::enforce_consistent_e()                   1  7.606e-05  8.583e-05  9.638e-05   0.00%
Castro::construct_old_source()                  50  3.084e-05   7.17e-05   9.55e-05   0.00%
Castro::Castro()                                 1  5.553e-05  6.932e-05  8.701e-05   0.00%
Castro::apply_source_to_state()                 20  3.597e-05  5.749e-05   8.61e-05   0.00%
Castro::do_old_sources()                        10  3.805e-05  6.996e-05  8.344e-05   0.00%
FillPatchSingleLevel                            41  6.246e-05  6.716e-05  7.121e-05   0.00%
Amr::writeSmallPlotFile()                        1  3.156e-05  5.876e-05  6.944e-05   0.00%
Amr::FinalizeInit()                              1   3.74e-06  1.246e-05  6.854e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.782e-05  4.815e-05  6.579e-05   0.00%
FabArrayBase::getCPC()                          41  5.379e-05  5.796e-05  6.538e-05   0.00%
Castro::construct_old_gravity()                 10  3.821e-05  5.538e-05  6.499e-05   0.00%
Castro::finalize_advance()                      10  5.077e-05  5.453e-05  5.986e-05   0.00%
Castro::construct_new_gravity()                 10  3.724e-05  4.522e-05  4.963e-05   0.00%
Castro::advance()                               10  3.568e-05  4.312e-05  4.882e-05   0.00%
Amr::defBaseLevel()                              1   1.72e-05  2.281e-05  3.762e-05   0.00%
Castro::enforce_speed_limit()                   62  2.722e-05  3.177e-05  3.432e-05   0.00%
FabArrayBase::CPC::define()                      2  2.234e-05   2.68e-05   3.42e-05   0.00%
Castro::check_for_nan()                         20  2.097e-05   2.28e-05  2.424e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.322e-05  1.682e-05  2.255e-05   0.00%
Castro::FluxRegCrseInit                         10  5.617e-06  1.148e-05  1.748e-05   0.00%
Amr::initSubcycle()                              1  8.195e-06  1.096e-05  1.691e-05   0.00%
Castro::post_timestep()                         10  1.064e-05  1.328e-05  1.642e-05   0.00%
Gravity::swapTimeLevels()                       10  5.485e-06  1.259e-05  1.634e-05   0.00%
Castro::create_source_corrector()               10   6.62e-06  1.061e-05  1.413e-05   0.00%
Gravity::set_mass_offset()                      10  7.077e-06  9.415e-06  1.087e-05   0.00%
Castro::computeNewDt()                           9   6.71e-06  9.026e-06  1.055e-05   0.00%
Amr::InitializeInit()                            1  2.131e-06  4.459e-06  8.356e-06   0.00%
Castro::retry_advance_ctu()                     10  5.664e-06  7.161e-06  7.908e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.932e-06  4.672e-06   5.82e-06   0.00%
Castro::computeInitialDt()                       2  3.872e-06  4.415e-06  4.924e-06   0.00%
Castro::post_regrid()                            1  1.605e-06   3.23e-06  4.915e-06   0.00%
Castro::post_init()                              1  2.708e-06  3.299e-06  3.905e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.009e-06  2.356e-06  2.969e-06   0.00%
Amr::init()                                      1  1.778e-06  2.018e-06  2.409e-06   0.00%
Amr::initialInit()                               1  1.142e-06  1.598e-06  2.225e-06   0.00%
DistributionMapping::Distribute()                1  1.026e-06  1.182e-06  1.596e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.614      3.616      3.616 100.00%
Amr::coarseTimeStep()                           10      3.418      3.418      3.418  94.53%
Amr::timeStep()                                 10      3.408      3.408      3.409  94.28%
Castro::advance()                               10      3.391      3.392      3.392  93.81%
Castro::subcycle_advance_ctu()                  10      3.319      3.322      3.329  92.07%
Castro::do_advance_ctu()                        10      3.319      3.321      3.329  92.07%
Castro::construct_ctu_hydro_source()            10      2.115      2.391      2.489  68.84%
Gravity::make_radial_gravity()                  21     0.3435      0.358     0.3821  10.57%
Gravity::get_new_grav_vector()                  11     0.2304     0.2325      0.234   6.47%
Castro::construct_old_gravity()                 10      0.203     0.2153      0.233   6.44%
Gravity::get_old_grav_vector()                  10     0.2021     0.2144     0.2325   6.43%
FillPatchIterator::Initialize                   41     0.1794     0.1883     0.2084   5.76%
Castro::construct_new_gravity()                 10     0.2036      0.205     0.2062   5.70%
Amr::init()                                      1     0.1822     0.1824     0.1825   5.05%
FillPatchSingleLevel                            41       0.13     0.1448     0.1821   5.04%
Castro::initialize_do_advance()                 10     0.1596      0.171     0.1783   4.93%
Amr::initialInit()                               1      0.168     0.1686      0.169   4.67%
FabArray::ParallelCopy()                        41    0.08665     0.1026     0.1446   4.00%
Amr::InitializeInit()                            1     0.0578     0.1001     0.1381   3.82%
Amr::defBaseLevel()                              1     0.0578     0.1001     0.1381   3.82%
Castro::expand_state()                          10     0.1161     0.1236     0.1291   3.57%
Castro::initData()                               1    0.04224    0.08488     0.1222   3.38%
Castro::clean_state()                           62     0.1134     0.1182     0.1214   3.36%
FabArray::ParallelCopy_finish()                 41    0.03946    0.05924     0.1181   3.27%
Amr::FinalizeInit()                              1    0.03046    0.06844      0.111   3.07%
Castro::estTimeStep()                           21     0.0275    0.06748     0.1085   3.00%
Castro::computeInitialDt()                       2   0.001859    0.03968    0.08209   2.27%
Castro::initialize_advance()                    10    0.06214    0.07039    0.07274   2.01%
Castro::computeTemp()                           63      0.066    0.07036    0.07256   2.01%
FabArray::setVal()                             143    0.05941    0.06849    0.07123   1.97%
Castro::do_new_sources()                        10     0.0533    0.06322    0.06554   1.81%
Castro::reset_internal_energy(MultiFab)         63    0.04783    0.05099    0.05307   1.47%
Castro::reset_internal_energy(Fab)            1008    0.04753    0.05068    0.05275   1.46%
Castro::do_old_sources()                        10    0.03355    0.04553    0.04915   1.36%
FabArray::ParallelCopy_nowait()                 41    0.02642    0.04324    0.04889   1.35%
StateDataPhysBCFunct::()                        41     0.0347    0.04214      0.046   1.27%
StateData::FillBoundary(geom)                   41    0.03459    0.04201    0.04587   1.27%
Castro::normalize_species()                     62    0.04144    0.04181    0.04269   1.18%
Castro::construct_new_source()                  50    0.02439    0.02948     0.0313   0.87%
Castro::post_init()                              1    0.02782    0.02818    0.02851   0.79%
Gravity::interpolate_monopole_grav()            21    0.02694    0.02769    0.02824   0.78%
Castro::construct_new_gravity_source()          10    0.01953    0.02303     0.0243   0.67%
Amr::writePlotFile()                             2    0.02308    0.02334    0.02367   0.65%
Castro::post_timestep()                         10    0.01577    0.01616    0.01677   0.46%
Castro::apply_source_to_state()                 20   0.008617    0.01427    0.01614   0.45%
MultiFab::Saxpy()                               20   0.008581    0.01422    0.01608   0.44%
VisMF::Write(FabArray)                           2    0.01125    0.01196     0.0156   0.43%
Castro::check_for_nan()                         20   0.009366    0.01094    0.01242   0.34%
MultiFab::contains_nan()                        20   0.009345    0.01092    0.01239   0.34%
FabArray::setDomainBndry()                      41   0.004899    0.01036    0.01232   0.34%
Castro::construct_old_source()                  50   0.004946    0.01012    0.01205   0.33%
Castro::construct_old_gravity_source()          10   0.004915    0.01005    0.01197   0.33%
Castro::Castro()                                 1   0.008851   0.009757    0.01046   0.29%
Castro::enforce_min_density()                   62   0.006137    0.00676   0.007261   0.20%
Castro::initMFs()                                1    0.00555   0.006028    0.00713   0.20%
Castro::computeNewDt()                           9   0.007064   0.007071   0.007079   0.20%
AmrLevel::AmrLevel(dm)                           1   0.004592   0.005253   0.006835   0.19%
StateData::define()                              4   0.004579   0.005236   0.006813   0.19%
Castro::swap_state_time_levels()                10   0.002878   0.003727   0.004302   0.12%
Castro::post_regrid()                            1   0.001524   0.001641   0.001955   0.05%
Castro::buildMetrics()                           1   0.001445   0.001546   0.001616   0.04%
Castro::finalize_do_advance()                   10  0.0005254    0.00109   0.001259   0.03%
Amr::InitAmr()                                   1  0.0002738  0.0003728  0.0005368   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.576e-05  0.0002257  0.0003513   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.341e-06  0.0002199  0.0003446   0.01%
FabArrayBase::getCPC()                          41  7.705e-05  8.477e-05  9.959e-05   0.00%
Castro::enforce_consistent_e()                   1  7.606e-05  8.583e-05  9.638e-05   0.00%
Castro::finalize_advance()                      10  6.122e-05  6.836e-05  7.609e-05   0.00%
Amr::writeSmallPlotFile()                        1  3.156e-05  5.876e-05  6.944e-05   0.00%
Castro::enforce_speed_limit()                   62  2.722e-05  3.177e-05  3.432e-05   0.00%
FabArrayBase::CPC::define()                      2  2.234e-05   2.68e-05   3.42e-05   0.00%
Castro::FluxRegCrseInit                         10  5.617e-06  1.148e-05  1.748e-05   0.00%
Amr::initSubcycle()                              1  8.195e-06  1.096e-05  1.691e-05   0.00%
Gravity::swapTimeLevels()                       10  5.485e-06  1.259e-05  1.634e-05   0.00%
Castro::create_source_corrector()               10   6.62e-06  1.061e-05  1.413e-05   0.00%
Gravity::set_mass_offset()                      10  7.077e-06  9.415e-06  1.087e-05   0.00%
Castro::retry_advance_ctu()                     10  5.664e-06  7.161e-06  7.908e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.009e-06  2.356e-06  2.969e-06   0.00%
DistributionMapping::Distribute()                1  1.026e-06  1.182e-06  1.596e-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.07-5-g557aae84902f) finalized