MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.08-1-g94693291667b) initialized

Starting run at 05:39:03 UTC on 2022-08-02.
Successfully read inputs file ... 

Castro git describe: 22.08
AMReX git describe: 22.08-1-g946932916
Microphysics git describe: 22.08

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

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

[STEP 1] Coarse TimeStep time: 0.36576069
[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.344518818
[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.343579793
[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.341900699
[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.355878168
[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.353665019
[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.338164364
[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.33935421
[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.337764478
[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.339087069
[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.010312887  seconds


Ending run at 05:39:07 UTC on 2022-08-02.
Run time = 3.656719107
Run time without initialization = 3.477463967

  Average number of zones advanced per microsecond: 0.754
  Average number of zones advanced per microsecond per rank: 0.094



TinyProfiler total time across processes [min...avg...max]: 3.655 ... 3.656 ... 3.657

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1711     2.4127     2.5099  68.64%
Castro::do_advance_ctu()                        10    0.04003     0.1382     0.3848  10.52%
Gravity::make_radial_gravity()                  21     0.3391     0.3542      0.373  10.20%
Castro::initData()                               1     0.0391    0.07718     0.1143   3.13%
Castro::estTimeStep()                           21    0.03154    0.06804     0.1076   2.94%
FabArray::ParallelCopy_finish()                 41    0.04626    0.06068     0.1044   2.85%
FabArray::setVal()                             143    0.06969    0.07142    0.07305   2.00%
Castro::reset_internal_energy(Fab)            1008    0.04862    0.05004    0.05175   1.42%
FabArray::ParallelCopy_nowait()                 41    0.03098    0.04519    0.05055   1.38%
StateData::FillBoundary(geom)                   41    0.03744    0.04181    0.04643   1.27%
Castro::normalize_species()                     62    0.04077    0.04204    0.04347   1.19%
FillPatchIterator::Initialize                   41    0.02502     0.0366    0.04063   1.11%
Gravity::interpolate_monopole_grav()            21    0.02702    0.02766    0.02803   0.77%
Castro::construct_new_gravity_source()          10    0.02296    0.02391    0.02642   0.72%
Castro::expand_state()                          10   0.009949    0.01804    0.02179   0.60%
Castro::computeTemp()                           63    0.01773    0.01914    0.02024   0.55%
Castro::initialize_do_advance()                 10    0.01043     0.0177    0.01988   0.54%
MultiFab::Saxpy()                               20    0.01235    0.01503     0.0163   0.45%
VisMF::Write(FabArray)                           2    0.01216    0.01291    0.01553   0.42%
Amr::writePlotFile()                             2    0.01046     0.0131    0.01377   0.38%
Gravity::get_new_grav_vector()                  11    0.01023    0.01223    0.01334   0.36%
MultiFab::contains_nan()                        20     0.0107    0.01154    0.01293   0.35%
FabArray::setDomainBndry()                      41   0.004999     0.0105    0.01246   0.34%
Castro::construct_old_gravity_source()          10   0.005798    0.01058    0.01224   0.33%
Castro::initialize_advance()                    10    0.01064    0.01091    0.01137   0.31%
Gravity::get_old_grav_vector()                  10   0.006282   0.009636    0.01118   0.31%
StateData::define()                              4   0.005557   0.006332   0.008208   0.22%
Castro::initMFs()                                1   0.006621   0.007128    0.00812   0.22%
Castro::construct_new_source()                  50   0.005324    0.00666    0.00721   0.20%
Castro::enforce_min_density()                   62   0.006224   0.006762   0.007209   0.20%
main()                                           1   0.004563    0.00616    0.00684   0.19%
Castro::swap_state_time_levels()                10   0.003454    0.00463   0.004827   0.13%
Amr::coarseTimeStep()                           10  0.0008192   0.001668   0.003231   0.09%
Castro::buildMetrics()                           1   0.001563   0.001795    0.00234   0.06%
Castro::finalize_do_advance()                   10  0.0006587   0.001412   0.001659   0.05%
Amr::InitAmr()                                   1  0.0002735  0.0003483  0.0004878   0.01%
Amr::timeStep()                                 10    0.00017  0.0002525  0.0004682   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002857  0.0003064  0.0003307   0.01%
DistributionMapping::LeastUsedCPUs()             1  6.759e-06  0.0001744  0.0002607   0.01%
Castro::clean_state()                           62  0.0001373  0.0001583  0.0001798   0.00%
StateDataPhysBCFunct::()                        41  0.0001081  0.0001341  0.0001593   0.00%
Amr::FinalizeInit()                              1  3.866e-06  1.826e-05  0.0001132   0.00%
Castro::enforce_consistent_e()                   1  8.048e-05  8.973e-05  0.0001094   0.00%
FabArray::ParallelCopy()                        41  8.522e-05  9.223e-05  9.985e-05   0.00%
Castro::construct_old_source()                  50  4.005e-05  7.318e-05  9.351e-05   0.00%
Castro::do_new_sources()                        10  5.173e-05  7.719e-05  9.271e-05   0.00%
Castro::do_old_sources()                        10  4.612e-05  7.091e-05   8.12e-05   0.00%
FillPatchSingleLevel                            41  6.257e-05  7.132e-05  8.005e-05   0.00%
Castro::Castro()                                 1  4.772e-05  5.864e-05  7.444e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.549e-05   6.05e-05  7.361e-05   0.00%
FabArrayBase::getCPC()                          41  4.892e-05  5.961e-05   7.18e-05   0.00%
Castro::advance()                               10  3.829e-05   4.61e-05  7.092e-05   0.00%
Castro::apply_source_to_state()                 20  3.834e-05  4.881e-05  6.583e-05   0.00%
Castro::construct_old_gravity()                 10  4.109e-05  5.321e-05   6.58e-05   0.00%
Castro::finalize_advance()                      10  5.078e-05  5.431e-05  5.818e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.756e-05  4.846e-05  5.638e-05   0.00%
Castro::construct_new_gravity()                 10  3.907e-05  4.733e-05  5.341e-05   0.00%
FabArrayBase::CPC::define()                      2  2.388e-05  2.772e-05  3.843e-05   0.00%
Amr::defBaseLevel()                              1  1.729e-05  2.526e-05  3.461e-05   0.00%
Castro::enforce_speed_limit()                   62  2.493e-05  2.793e-05   3.31e-05   0.00%
Castro::post_timestep()                         10  1.445e-05  2.119e-05  2.905e-05   0.00%
Castro::check_for_nan()                         20  1.986e-05  2.383e-05  2.815e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.197e-05  1.731e-05  2.591e-05   0.00%
Amr::initSubcycle()                              1   8.22e-06  1.032e-05  1.545e-05   0.00%
Gravity::swapTimeLevels()                       10  6.741e-06  1.179e-05  1.497e-05   0.00%
Castro::create_source_corrector()               10  7.167e-06  1.105e-05  1.383e-05   0.00%
Castro::computeNewDt()                           9  6.768e-06  9.261e-06  1.188e-05   0.00%
Gravity::set_mass_offset()                      10  6.492e-06  9.251e-06  1.142e-05   0.00%
Castro::post_init()                              1  2.936e-06  6.368e-06  1.096e-05   0.00%
Castro::FluxRegCrseInit                         10  5.861e-06  8.315e-06  9.847e-06   0.00%
Amr::InitializeInit()                            1  1.876e-06  4.377e-06   8.23e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.512e-06  6.332e-06   8.09e-06   0.00%
Castro::retry_advance_ctu()                     10  4.496e-06  5.936e-06  7.026e-06   0.00%
Castro::computeInitialDt()                       2   3.01e-06   3.61e-06  4.868e-06   0.00%
Castro::post_regrid()                            1  1.811e-06  2.807e-06  4.694e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.093e-06  2.375e-06  3.038e-06   0.00%
Amr::init()                                      1  2.003e-06   2.31e-06  2.878e-06   0.00%
Amr::initialInit()                               1  1.171e-06  1.537e-06  2.575e-06   0.00%
DistributionMapping::Distribute()                1   9.04e-07  1.283e-06  1.982e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.655      3.656      3.657 100.00%
Amr::coarseTimeStep()                           10       3.46       3.46       3.46  94.63%
Amr::timeStep()                                 10      3.449      3.451      3.451  94.38%
Castro::advance()                               10      3.434      3.434      3.435  93.93%
Castro::subcycle_advance_ctu()                  10      3.359      3.361      3.363  91.98%
Castro::do_advance_ctu()                        10      3.359      3.361      3.363  91.97%
Castro::construct_ctu_hydro_source()            10      2.171      2.413       2.51  68.64%
Gravity::make_radial_gravity()                  21     0.3391     0.3542      0.373  10.20%
Gravity::get_new_grav_vector()                  11     0.2307     0.2326     0.2338   6.39%
Castro::construct_old_gravity()                 10     0.1997     0.2133     0.2267   6.20%
Gravity::get_old_grav_vector()                  10     0.1988     0.2124     0.2256   6.17%
Castro::construct_new_gravity()                 10     0.2061     0.2075     0.2087   5.71%
FillPatchIterator::Initialize                   41     0.1893     0.1952     0.2033   5.56%
Castro::initialize_do_advance()                 10      0.163     0.1784     0.1889   5.16%
Amr::init()                                      1     0.1786     0.1788     0.1789   4.89%
FillPatchSingleLevel                            41     0.1371     0.1481     0.1733   4.74%
Amr::initialInit()                               1     0.1629     0.1631     0.1633   4.47%
Castro::expand_state()                          10     0.1204       0.13      0.139   3.80%
FabArray::ParallelCopy()                        41     0.0953     0.1061     0.1355   3.71%
Amr::InitializeInit()                            1    0.05989    0.09847     0.1349   3.69%
Amr::defBaseLevel()                              1    0.05989    0.09847     0.1349   3.69%
Castro::clean_state()                           62     0.1138     0.1176     0.1211   3.31%
Castro::initData()                               1    0.04269      0.081     0.1185   3.24%
Castro::estTimeStep()                           21    0.03154    0.06804     0.1076   2.94%
FabArray::ParallelCopy_finish()                 41    0.04626    0.06068     0.1044   2.85%
Amr::FinalizeInit()                              1    0.02825    0.06467     0.1032   2.82%
Castro::computeInitialDt()                       2   0.001715    0.03804    0.07656   2.09%
Castro::initialize_advance()                    10    0.07117    0.07361    0.07506   2.05%
FabArray::setVal()                             143    0.06969    0.07142    0.07305   2.00%
Castro::computeTemp()                           63    0.06667    0.06949    0.07197   1.97%
Castro::do_new_sources()                        10    0.06493    0.06613    0.06951   1.90%
Castro::reset_internal_energy(MultiFab)         63    0.04894    0.05035    0.05205   1.42%
Castro::reset_internal_energy(Fab)            1008    0.04862    0.05004    0.05175   1.42%
Castro::do_old_sources()                        10    0.03748    0.04687    0.05102   1.40%
FabArray::ParallelCopy_nowait()                 41    0.03108    0.04528    0.05063   1.38%
StateDataPhysBCFunct::()                        41    0.03755    0.04194    0.04658   1.27%
StateData::FillBoundary(geom)                   41    0.03744    0.04181    0.04643   1.27%
Castro::normalize_species()                     62    0.04077    0.04204    0.04347   1.19%
Castro::construct_new_source()                  50    0.02832    0.03057    0.03174   0.87%
Gravity::interpolate_monopole_grav()            21    0.02702    0.02766    0.02803   0.77%
Castro::construct_new_gravity_source()          10    0.02296    0.02391    0.02642   0.72%
Amr::writePlotFile()                             2    0.02591    0.02601    0.02618   0.72%
Castro::post_init()                              1    0.02558    0.02588    0.02609   0.71%
Castro::post_timestep()                         10    0.01509    0.01589    0.01664   0.45%
Castro::apply_source_to_state()                 20    0.01239    0.01508    0.01636   0.45%
MultiFab::Saxpy()                               20    0.01235    0.01503     0.0163   0.45%
VisMF::Write(FabArray)                           2    0.01216    0.01291    0.01553   0.42%
Castro::check_for_nan()                         20    0.01072    0.01157    0.01295   0.35%
MultiFab::contains_nan()                        20     0.0107    0.01154    0.01293   0.35%
FabArray::setDomainBndry()                      41   0.004999     0.0105    0.01246   0.34%
Castro::construct_old_source()                  50   0.005838    0.01066    0.01233   0.34%
Castro::construct_old_gravity_source()          10   0.005798    0.01058    0.01224   0.33%
Castro::Castro()                                 1    0.01052    0.01091    0.01165   0.32%
AmrLevel::AmrLevel(dm)                           1   0.005571   0.006349   0.008231   0.23%
StateData::define()                              4   0.005557   0.006332   0.008208   0.22%
Castro::initMFs()                                1   0.006621   0.007128    0.00812   0.22%
Castro::enforce_min_density()                   62   0.006224   0.006762   0.007209   0.20%
Castro::computeNewDt()                           9   0.007163   0.007183   0.007191   0.20%
Castro::swap_state_time_levels()                10   0.003454    0.00463   0.004827   0.13%
Castro::buildMetrics()                           1   0.001676   0.001938    0.00255   0.07%
Castro::post_regrid()                            1   0.001579   0.001665   0.001809   0.05%
Castro::finalize_do_advance()                   10  0.0006587   0.001412   0.001659   0.05%
Amr::InitAmr()                                   1  0.0002824  0.0003586  0.0005033   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.593e-05   0.000182  0.0002696   0.01%
DistributionMapping::LeastUsedCPUs()             1  6.759e-06  0.0001744  0.0002607   0.01%
Castro::enforce_consistent_e()                   1  8.048e-05  8.973e-05  0.0001094   0.00%
FabArrayBase::getCPC()                          41  7.288e-05  8.733e-05   0.000105   0.00%
Amr::writeSmallPlotFile()                        1  2.549e-05   6.05e-05  7.361e-05   0.00%
Castro::finalize_advance()                      10  5.908e-05    6.5e-05  6.918e-05   0.00%
FabArrayBase::CPC::define()                      2  2.388e-05  2.772e-05  3.843e-05   0.00%
Castro::enforce_speed_limit()                   62  2.493e-05  2.793e-05   3.31e-05   0.00%
Amr::initSubcycle()                              1   8.22e-06  1.032e-05  1.545e-05   0.00%
Gravity::swapTimeLevels()                       10  6.741e-06  1.179e-05  1.497e-05   0.00%
Castro::create_source_corrector()               10  7.167e-06  1.105e-05  1.383e-05   0.00%
Gravity::set_mass_offset()                      10  6.492e-06  9.251e-06  1.142e-05   0.00%
Castro::FluxRegCrseInit                         10  5.861e-06  8.315e-06  9.847e-06   0.00%
Castro::retry_advance_ctu()                     10  4.496e-06  5.936e-06  7.026e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.093e-06  2.375e-06  3.038e-06   0.00%
DistributionMapping::Distribute()                1   9.04e-07  1.283e-06  1.982e-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.08-1-g94693291667b) finalized