MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.06-51-g8fb23ec17a58) initialized

Starting run at 18:42:38 UTC on 2022-06-30.
Successfully read inputs file ... 

Castro git describe: 22.06-44-g18b3ffd36
AMReX git describe: 22.06-51-g8fb23ec17
Microphysics git describe: 22.06-7-ga6a75714

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

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

[STEP 1] Coarse TimeStep time: 0.360987166
[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.341508194
[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.341412512
[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.342066269
[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.352362216
[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.348677344
[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.337377244
[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.33488446
[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.336810903
[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.336692054
[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.00981849  seconds


Ending run at 18:42:42 UTC on 2022-06-30.
Run time = 3.632364963
Run time without initialization = 3.449108741

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



TinyProfiler total time across processes [min...avg...max]: 3.63 ... 3.632 ... 3.632

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1116     2.3871     2.4952  68.69%
Castro::do_advance_ctu()                        10    0.02982     0.1416     0.4214  11.60%
Gravity::make_radial_gravity()                  21      0.343     0.3559     0.3763  10.36%
Castro::initData()                               1    0.03839    0.07934      0.119   3.28%
Castro::estTimeStep()                           21    0.02935    0.06949     0.1109   3.05%
FabArray::ParallelCopy_finish()                 41    0.04156    0.05981     0.1028   2.83%
FabArray::setVal()                             143    0.06884    0.07253    0.07475   2.06%
Castro::reset_internal_energy(Fab)            1008    0.04688    0.05007    0.05294   1.46%
FabArray::ParallelCopy_nowait()                 41    0.03077    0.04439    0.04953   1.36%
StateData::FillBoundary(geom)                   41    0.03467    0.04245    0.04762   1.31%
Castro::normalize_species()                     62    0.04125    0.04179    0.04215   1.16%
FillPatchIterator::Initialize                   41     0.0226    0.03394    0.03818   1.05%
Gravity::interpolate_monopole_grav()            21    0.02708    0.02812      0.029   0.80%
Castro::construct_new_gravity_source()          10    0.02331    0.02407    0.02517   0.69%
Castro::expand_state()                          10    0.01301    0.02013    0.02357   0.65%
Castro::computeTemp()                           63    0.01758    0.01905     0.0203   0.56%
Castro::initialize_do_advance()                 10    0.01447    0.01758     0.0187   0.51%
MultiFab::Saxpy()                               20     0.0123     0.0152    0.01657   0.46%
VisMF::Write(FabArray)                           2    0.01086    0.01201    0.01598   0.44%
Amr::writePlotFile()                             2   0.008024    0.01202    0.01306   0.36%
MultiFab::contains_nan()                        20    0.01028    0.01138    0.01258   0.35%
Gravity::get_new_grav_vector()                  11   0.009668    0.01162    0.01257   0.35%
FabArray::setDomainBndry()                      41   0.005588    0.01067    0.01249   0.34%
Castro::construct_old_gravity_source()          10   0.007148    0.01082    0.01243   0.34%
Castro::initialize_advance()                    10    0.01058    0.01078     0.0112   0.31%
Gravity::get_old_grav_vector()                  10   0.006331   0.009636      0.011   0.30%
Castro::construct_new_source()                  50    0.00514   0.006643   0.007241   0.20%
Castro::enforce_min_density()                   62    0.00654   0.006834   0.007212   0.20%
Castro::initMFs()                                1   0.005609   0.006031    0.00674   0.19%
main()                                           1   0.003539    0.00541    0.00578   0.16%
StateData::define()                              4   0.004617   0.004878   0.005148   0.14%
Castro::swap_state_time_levels()                10   0.003086   0.004064   0.004539   0.12%
Amr::coarseTimeStep()                           10  0.0009175   0.001349   0.001752   0.05%
Castro::finalize_do_advance()                   10   0.001118   0.001492   0.001647   0.05%
Castro::buildMetrics()                           1   0.001318   0.001386   0.001498   0.04%
Amr::timeStep()                                 10  0.0001868  0.0002505  0.0005265   0.01%
Amr::InitAmr()                                   1  0.0002669  0.0002964  0.0003825   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002957  0.0003063  0.0003161   0.01%
Castro::clean_state()                           62  0.0001367  0.0001628   0.000183   0.01%
StateDataPhysBCFunct::()                        41  0.0001035  0.0001293  0.0001504   0.00%
DistributionMapping::LeastUsedCPUs()             1  6.615e-06  8.556e-05  0.0001126   0.00%
Castro::enforce_consistent_e()                   1  8.156e-05  8.946e-05  0.0001015   0.00%
Castro::do_new_sources()                        10  4.723e-05  7.735e-05  9.973e-05   0.00%
Amr::FinalizeInit()                              1  3.841e-06  1.707e-05  9.772e-05   0.00%
Castro::construct_old_source()                  50  3.233e-05  7.776e-05  9.663e-05   0.00%
FabArray::ParallelCopy()                        41   7.89e-05  8.871e-05  9.434e-05   0.00%
Castro::Castro()                                 1  5.101e-05  7.064e-05  9.108e-05   0.00%
Castro::do_old_sources()                        10  4.245e-05  6.996e-05  8.547e-05   0.00%
FillPatchSingleLevel                            41  6.142e-05  6.883e-05  7.575e-05   0.00%
Amr::writeSmallPlotFile()                        1  3.116e-05  6.121e-05  7.114e-05   0.00%
FabArrayBase::getCPC()                          41  4.821e-05  6.039e-05  7.046e-05   0.00%
Castro::apply_source_to_state()                 20  3.078e-05  5.188e-05  6.772e-05   0.00%
Castro::construct_old_gravity()                 10  3.916e-05  5.458e-05  6.324e-05   0.00%
Castro::construct_new_gravity()                 10  4.004e-05  4.845e-05  5.762e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.671e-05  4.677e-05  5.665e-05   0.00%
Castro::finalize_advance()                      10   4.86e-05   5.32e-05  5.559e-05   0.00%
Castro::advance()                               10   3.74e-05  4.269e-05  4.713e-05   0.00%
FabArrayBase::CPC::define()                      2  2.347e-05   2.78e-05  3.519e-05   0.00%
Castro::enforce_speed_limit()                   62  2.513e-05  2.955e-05  3.141e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.281e-05  1.996e-05  3.089e-05   0.00%
Castro::check_for_nan()                         20  2.018e-05  2.212e-05  2.536e-05   0.00%
Amr::defBaseLevel()                              1  1.763e-05  2.067e-05   2.47e-05   0.00%
Castro::post_timestep()                         10  1.166e-05  1.372e-05  1.731e-05   0.00%
Gravity::swapTimeLevels()                       10  6.798e-06  1.153e-05  1.511e-05   0.00%
Amr::init()                                      1  2.056e-06  3.931e-06  1.499e-05   0.00%
Castro::create_source_corrector()               10   7.27e-06  1.042e-05  1.316e-05   0.00%
Gravity::set_mass_offset()                      10   7.23e-06  9.248e-06  1.122e-05   0.00%
Castro::FluxRegCrseInit                         10  7.129e-06   9.36e-06  1.094e-05   0.00%
Castro::computeNewDt()                           9  6.592e-06  8.658e-06  1.088e-05   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1   3.61e-06  5.289e-06  9.414e-06   0.00%
Amr::initSubcycle()                              1  8.024e-06  8.855e-06  9.254e-06   0.00%
Castro::retry_advance_ctu()                     10  4.588e-06  6.047e-06  7.685e-06   0.00%
Amr::InitializeInit()                            1  2.169e-06  3.645e-06  5.923e-06   0.00%
Castro::post_init()                              1  3.271e-06   4.04e-06  5.253e-06   0.00%
Castro::computeInitialDt()                       2  3.319e-06  4.065e-06  5.251e-06   0.00%
Castro::post_regrid()                            1  1.577e-06  2.633e-06  4.417e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.158e-06  2.725e-06  3.256e-06   0.00%
Amr::initialInit()                               1  1.096e-06  1.648e-06  2.303e-06   0.00%
DistributionMapping::Distribute()                1   8.98e-07  1.038e-06  1.176e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1       3.63      3.632      3.632 100.00%
Amr::coarseTimeStep()                           10      3.434      3.434      3.434  94.52%
Amr::timeStep()                                 10      3.424      3.424      3.425  94.28%
Castro::advance()                               10      3.408      3.408      3.408  93.83%
Castro::subcycle_advance_ctu()                  10      3.332      3.335       3.34  91.95%
Castro::do_advance_ctu()                        10      3.332      3.335       3.34  91.95%
Castro::construct_ctu_hydro_source()            10      2.112      2.387      2.495  68.69%
Gravity::make_radial_gravity()                  21      0.343     0.3559     0.3763  10.36%
Gravity::get_new_grav_vector()                  11     0.2312     0.2328     0.2339   6.44%
Castro::construct_old_gravity()                 10     0.2054     0.2154     0.2301   6.33%
Gravity::get_old_grav_vector()                  10     0.2045     0.2145      0.229   6.31%
Castro::construct_new_gravity()                 10     0.2046     0.2059     0.2068   5.69%
FillPatchIterator::Initialize                   41     0.1822     0.1916     0.1993   5.49%
Castro::initialize_do_advance()                 10     0.1649     0.1759     0.1833   5.05%
Amr::init()                                      1     0.1827     0.1828     0.1828   5.03%
FillPatchSingleLevel                            41     0.1322      0.147     0.1707   4.70%
Amr::initialInit()                               1     0.1684     0.1687     0.1689   4.65%
Amr::InitializeInit()                            1     0.0569    0.09832     0.1386   3.82%
Amr::defBaseLevel()                              1     0.0569    0.09832     0.1386   3.82%
Castro::expand_state()                          10     0.1199     0.1275     0.1338   3.68%
FabArray::ParallelCopy()                        41    0.08888     0.1044     0.1338   3.68%
Castro::initData()                               1    0.04275    0.08339     0.1226   3.37%
Castro::clean_state()                           62     0.1125     0.1173     0.1211   3.33%
Amr::FinalizeInit()                              1    0.03012    0.07035     0.1119   3.08%
Castro::estTimeStep()                           21    0.02935    0.06949     0.1109   3.05%
FabArray::ParallelCopy_finish()                 41    0.04156    0.05981     0.1028   2.83%
Castro::computeInitialDt()                       2   0.001619     0.0419    0.08335   2.29%
Castro::initialize_advance()                    10    0.06781    0.07338    0.07576   2.09%
FabArray::setVal()                             143    0.06884    0.07253    0.07475   2.06%
Castro::computeTemp()                           63    0.06477    0.06943    0.07354   2.02%
Castro::do_new_sources()                        10     0.0651    0.06608    0.06774   1.86%
Castro::reset_internal_energy(MultiFab)         63    0.04719    0.05038    0.05324   1.47%
Castro::reset_internal_energy(Fab)            1008    0.04688    0.05007    0.05294   1.46%
Castro::do_old_sources()                        10    0.03861    0.04699    0.05037   1.39%
FabArray::ParallelCopy_nowait()                 41    0.03088    0.04448    0.04961   1.37%
StateDataPhysBCFunct::()                        41    0.03479    0.04258    0.04777   1.32%
StateData::FillBoundary(geom)                   41    0.03467    0.04245    0.04762   1.31%
Castro::normalize_species()                     62    0.04125    0.04179    0.04215   1.16%
Castro::construct_new_source()                  50    0.03031    0.03071    0.03109   0.86%
Gravity::interpolate_monopole_grav()            21    0.02708    0.02812      0.029   0.80%
Castro::post_init()                              1    0.02748    0.02772    0.02791   0.77%
Castro::construct_new_gravity_source()          10    0.02331    0.02407    0.02517   0.69%
Amr::writePlotFile()                             2    0.02386    0.02404    0.02428   0.67%
Castro::apply_source_to_state()                 20    0.01233    0.01525    0.01664   0.46%
MultiFab::Saxpy()                               20     0.0123     0.0152    0.01657   0.46%
Castro::post_timestep()                         10    0.01559     0.0159    0.01634   0.45%
VisMF::Write(FabArray)                           2    0.01086    0.01201    0.01598   0.44%
Castro::check_for_nan()                         20     0.0103     0.0114    0.01261   0.35%
MultiFab::contains_nan()                        20    0.01028    0.01138    0.01258   0.35%
Castro::construct_old_source()                  50    0.00718    0.01089    0.01252   0.34%
FabArray::setDomainBndry()                      41   0.005588    0.01067    0.01249   0.34%
Castro::construct_old_gravity_source()          10   0.007148    0.01082    0.01243   0.34%
Castro::Castro()                                 1   0.009355   0.009914    0.01073   0.30%
Castro::enforce_min_density()                   62    0.00654   0.006834   0.007212   0.20%
Castro::computeNewDt()                           9    0.00701   0.007027   0.007039   0.19%
Castro::initMFs()                                1   0.005609   0.006031    0.00674   0.19%
AmrLevel::AmrLevel(dm)                           1   0.004638   0.004898   0.005164   0.14%
StateData::define()                              4   0.004617   0.004878   0.005148   0.14%
Castro::swap_state_time_levels()                10   0.003086   0.004064   0.004539   0.12%
Castro::post_regrid()                            1   0.001507   0.001628   0.001856   0.05%
Castro::buildMetrics()                           1   0.001439   0.001517    0.00165   0.05%
Castro::finalize_do_advance()                   10   0.001118   0.001492   0.001647   0.05%
Amr::InitAmr()                                   1  0.0002756  0.0003052  0.0003906   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.115e-05  9.189e-05  0.0001231   0.00%
DistributionMapping::LeastUsedCPUs()             1  6.615e-06  8.556e-05  0.0001126   0.00%
Castro::enforce_consistent_e()                   1  8.156e-05  8.946e-05  0.0001015   0.00%
FabArrayBase::getCPC()                          41  7.168e-05  8.819e-05  0.0001015   0.00%
Amr::writeSmallPlotFile()                        1  3.116e-05  6.121e-05  7.114e-05   0.00%
Castro::finalize_advance()                      10  5.848e-05  6.529e-05  6.897e-05   0.00%
FabArrayBase::CPC::define()                      2  2.347e-05   2.78e-05  3.519e-05   0.00%
Castro::enforce_speed_limit()                   62  2.513e-05  2.955e-05  3.141e-05   0.00%
Gravity::swapTimeLevels()                       10  6.798e-06  1.153e-05  1.511e-05   0.00%
Castro::create_source_corrector()               10   7.27e-06  1.042e-05  1.316e-05   0.00%
Gravity::set_mass_offset()                      10   7.23e-06  9.248e-06  1.122e-05   0.00%
Castro::FluxRegCrseInit                         10  7.129e-06   9.36e-06  1.094e-05   0.00%
Amr::initSubcycle()                              1  8.024e-06  8.855e-06  9.254e-06   0.00%
Castro::retry_advance_ctu()                     10  4.588e-06  6.047e-06  7.685e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.158e-06  2.725e-06  3.256e-06   0.00%
DistributionMapping::Distribute()                1   8.98e-07  1.038e-06  1.176e-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.06-51-g8fb23ec17a58) finalized