MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.06-33-g6f72de283c38) initialized

Starting run at 05:38:40 UTC on 2022-06-16.
Successfully read inputs file ... 

Castro git describe: 22.06-12-g556652b03
AMReX git describe: 22.06-33-g6f72de283
Microphysics git describe: 22.06-2-g35a553f4

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

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

[STEP 1] Coarse TimeStep time: 0.349103655
[STEP 1] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.339333489
[STEP 2] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.338390025
[STEP 3] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.33878414
[STEP 4] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.339086351
[STEP 5] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.380960855
[STEP 6] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.341877527
[STEP 7] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.350131318
[STEP 8] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.354828852
[STEP 9] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.34734614
[STEP 10] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

STEP = 10 TIME = 0.000571255455 DT = 7.045732848e-05

PLOTFILE: file = dustcollapse-3d-monopole_plt00010
Write plotfile time = 0.009993713  seconds


Ending run at 05:38:43 UTC on 2022-06-16.
Run time = 3.670815894
Run time without initialization = 3.496646696

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



TinyProfiler total time across processes [min...avg...max]: 3.668 ... 3.67 ... 3.671

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.0684     2.3861     2.5161  68.54%
Castro::do_advance_ctu()                        10    0.07211     0.2038     0.5242  14.28%
Gravity::make_radial_gravity()                  21     0.3453      0.359     0.3911  10.65%
FabArray::ParallelCopy_finish()                 41    0.03508    0.05601     0.1268   3.45%
Castro::initData()                               1    0.03803    0.07652     0.1143   3.11%
Castro::estTimeStep()                           21    0.02364    0.06185      0.113   3.08%
FabArray::setVal()                             143    0.05018    0.06884    0.07525   2.05%
Castro::reset_internal_energy(Fab)            1008    0.04601    0.04979    0.05172   1.41%
FabArray::ParallelCopy_nowait()                 41     0.0249    0.04348    0.04964   1.35%
StateData::FillBoundary(geom)                   41    0.03439    0.04191     0.0471   1.28%
Castro::normalize_species()                     62    0.04108    0.04194    0.04332   1.18%
FillPatchIterator::Initialize                   41    0.01948     0.0321    0.03676   1.00%
Gravity::interpolate_monopole_grav()            21    0.02724    0.02781    0.02802   0.76%
Castro::construct_new_gravity_source()          10     0.0181    0.02304    0.02438   0.66%
Castro::expand_state()                          10   0.009392    0.01992    0.02349   0.64%
Castro::computeTemp()                           63     0.0173    0.01898    0.02019   0.55%
Castro::initialize_do_advance()                 10   0.008268    0.01621    0.01829   0.50%
VisMF::Write(FabArray)                           2    0.01212    0.01284    0.01685   0.46%
MultiFab::Saxpy()                               20   0.008425    0.01437    0.01674   0.46%
FabArray::setDomainBndry()                      41   0.006121    0.01213     0.0153   0.42%
MultiFab::contains_nan()                        20   0.009788    0.01153    0.01393   0.38%
Gravity::get_new_grav_vector()                  11   0.008979    0.01155    0.01292   0.35%
Castro::construct_old_gravity_source()          10    0.00487   0.009997    0.01184   0.32%
Amr::writePlotFile()                             2   0.006812    0.01091    0.01175   0.32%
Gravity::get_old_grav_vector()                  10   0.005406   0.009718    0.01129   0.31%
Castro::initialize_advance()                    10   0.006781    0.01024    0.01103   0.30%
Castro::enforce_min_density()                   62   0.005885   0.006659   0.007138   0.19%
Castro::construct_new_source()                  50    0.00468   0.006464   0.007127   0.19%
main()                                           1   0.003592   0.005541   0.006078   0.17%
Castro::initMFs()                                1   0.005453   0.005477   0.005518   0.15%
StateData::define()                              4   0.004499   0.004529   0.004545   0.12%
Castro::swap_state_time_levels()                10   0.001687   0.003629   0.003969   0.11%
Amr::coarseTimeStep()                           10   0.001373   0.002475   0.003102   0.08%
Castro::finalize_do_advance()                   10  0.0005329   0.001182   0.001432   0.04%
Castro::buildMetrics()                           1   0.001291   0.001305   0.001322   0.04%
Amr::timeStep()                                 10  0.0001604  0.0002147  0.0004529   0.01%
Amr::InitAmr()                                   1  0.0002702  0.0002881  0.0003765   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002815  0.0003076  0.0003334   0.01%
Castro::clean_state()                           62  0.0001148  0.0001636  0.0001913   0.01%
StateDataPhysBCFunct::()                        41  8.908e-05  0.0001437  0.0001892   0.01%
FabArray::ParallelCopy()                        41  5.941e-05  8.463e-05  0.0001232   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.557e-06  9.571e-05  0.0001116   0.00%
Castro::construct_old_source()                  50   2.98e-05  7.278e-05  0.0001003   0.00%
Castro::enforce_consistent_e()                   1  7.703e-05  8.597e-05  9.487e-05   0.00%
Castro::Castro()                                 1  5.647e-05  7.027e-05  9.311e-05   0.00%
Castro::do_old_sources()                        10  3.267e-05  6.577e-05  8.184e-05   0.00%
Castro::subcycle_advance_ctu()                  10  2.822e-05  5.035e-05  8.163e-05   0.00%
Castro::do_new_sources()                        10  3.507e-05  6.502e-05  8.037e-05   0.00%
Amr::FinalizeInit()                              1   3.67e-06  1.358e-05  7.779e-05   0.00%
FillPatchSingleLevel                            41  4.997e-05  6.549e-05  7.521e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.356e-05  4.764e-05  6.953e-05   0.00%
Castro::construct_old_gravity()                 10  3.058e-05  5.362e-05  6.744e-05   0.00%
FabArrayBase::getCPC()                          41  3.654e-05  4.927e-05  6.059e-05   0.00%
Castro::finalize_advance()                      10  4.221e-05  5.142e-05  5.831e-05   0.00%
Castro::apply_source_to_state()                 20  2.483e-05  4.667e-05  5.812e-05   0.00%
Amr::defBaseLevel()                              1  2.643e-05  3.764e-05  5.406e-05   0.00%
Castro::construct_new_gravity()                 10  3.413e-05  4.437e-05  5.131e-05   0.00%
Castro::advance()                               10  2.695e-05  3.889e-05  4.631e-05   0.00%
Castro::post_timestep()                         10   1.21e-05  2.529e-05   4.48e-05   0.00%
Castro::check_for_nan()                         20  1.962e-05  2.593e-05  3.307e-05   0.00%
FabArrayBase::CPC::define()                      2  2.259e-05  2.535e-05  3.072e-05   0.00%
Castro::enforce_speed_limit()                   62  2.019e-05  2.514e-05  2.912e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.447e-05  1.753e-05  2.295e-05   0.00%
Gravity::swapTimeLevels()                       10  4.769e-06  1.159e-05  1.543e-05   0.00%
Castro::create_source_corrector()               10  5.729e-06   1.05e-05  1.406e-05   0.00%
Castro::post_init()                              1  8.053e-06  1.024e-05  1.273e-05   0.00%
Gravity::set_mass_offset()                      10  6.838e-06  1.039e-05   1.27e-05   0.00%
Castro::FluxRegCrseInit                         10  6.616e-06  9.257e-06  1.244e-05   0.00%
Castro::computeNewDt()                           9  7.174e-06  9.948e-06  1.162e-05   0.00%
Amr::initSubcycle()                              1  8.192e-06  8.514e-06  9.259e-06   0.00%
Castro::retry_advance_ctu()                     10  3.797e-06  5.594e-06  6.384e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.599e-06  4.254e-06  6.374e-06   0.00%
Amr::InitializeInit()                            1  2.001e-06  2.597e-06  5.601e-06   0.00%
Castro::post_regrid()                            1  1.931e-06  3.357e-06  5.522e-06   0.00%
Castro::computeInitialDt()                       2  3.771e-06  4.538e-06  5.083e-06   0.00%
Amr::init()                                      1  1.938e-06  2.323e-06  3.052e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.978e-06  2.261e-06   2.97e-06   0.00%
Amr::initialInit()                               1  1.126e-06   1.34e-06  1.455e-06   0.00%
DistributionMapping::Distribute()                1  1.131e-06  1.244e-06  1.338e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.668       3.67      3.671 100.00%
Amr::coarseTimeStep()                           10      3.481      3.481      3.481  94.82%
Amr::timeStep()                                 10      3.469       3.47      3.471  94.55%
Castro::advance()                               10      3.453      3.453      3.454  94.08%
Castro::subcycle_advance_ctu()                  10      3.375      3.383      3.401  92.66%
Castro::do_advance_ctu()                        10      3.375      3.383      3.401  92.66%
Castro::construct_ctu_hydro_source()            10      2.068      2.386      2.516  68.54%
Gravity::make_radial_gravity()                  21     0.3453      0.359     0.3911  10.65%
Castro::construct_old_gravity()                 10     0.2064     0.2178      0.243   6.62%
Gravity::get_old_grav_vector()                  10     0.2056      0.217     0.2425   6.61%
Gravity::get_new_grav_vector()                  11     0.2306     0.2322     0.2337   6.37%
FillPatchIterator::Initialize                   41     0.1766      0.186      0.212   5.78%
Castro::construct_new_gravity()                 10     0.2064     0.2083       0.21   5.72%
FillPatchSingleLevel                            41     0.1259     0.1418     0.1863   5.08%
Castro::initialize_do_advance()                 10     0.1637     0.1692     0.1739   4.74%
Amr::init()                                      1     0.1736     0.1737     0.1738   4.73%
Amr::initialInit()                               1     0.1598     0.1601     0.1603   4.37%
FabArray::ParallelCopy()                        41    0.08336    0.09965     0.1518   4.14%
Amr::InitializeInit()                            1     0.0569     0.0953     0.1331   3.63%
Amr::defBaseLevel()                              1     0.0569     0.0953     0.1331   3.63%
FabArray::ParallelCopy_finish()                 41    0.03508    0.05601     0.1268   3.45%
Castro::expand_state()                          10      0.116     0.1223     0.1265   3.45%
Castro::clean_state()                           62     0.1109     0.1169     0.1214   3.31%
Castro::initData()                               1    0.04213    0.08062     0.1185   3.23%
Castro::estTimeStep()                           21    0.02364    0.06185      0.113   3.08%
Amr::FinalizeInit()                              1    0.02719    0.06475     0.1031   2.81%
Castro::computeInitialDt()                       2   0.001673    0.03946    0.07789   2.12%
Castro::initialize_advance()                    10    0.05114    0.06979    0.07774   2.12%
FabArray::setVal()                             143    0.05018    0.06884    0.07525   2.05%
Castro::computeTemp()                           63    0.06359    0.06908    0.07223   1.97%
Castro::do_new_sources()                        10    0.05067    0.06255    0.06567   1.79%
Castro::reset_internal_energy(MultiFab)         63    0.04629     0.0501    0.05204   1.42%
Castro::reset_internal_energy(Fab)            1008    0.04601    0.04979    0.05172   1.41%
FabArray::ParallelCopy_nowait()                 41    0.02496    0.04356    0.04972   1.35%
Castro::do_old_sources()                        10    0.03188    0.04571    0.04959   1.35%
StateDataPhysBCFunct::()                        41    0.03448    0.04205    0.04727   1.29%
StateData::FillBoundary(geom)                   41    0.03439    0.04191     0.0471   1.28%
Castro::normalize_species()                     62    0.04108    0.04194    0.04332   1.18%
Castro::construct_new_source()                  50    0.02278     0.0295    0.03139   0.86%
Gravity::interpolate_monopole_grav()            21    0.02724    0.02781    0.02802   0.76%
Castro::post_init()                              1     0.0243    0.02458    0.02487   0.68%
Castro::construct_new_gravity_source()          10     0.0181    0.02304    0.02438   0.66%
Amr::writePlotFile()                             2    0.02351    0.02374    0.02403   0.65%
Castro::post_timestep()                         10    0.01572    0.01604    0.01724   0.47%
VisMF::Write(FabArray)                           2    0.01212    0.01284    0.01685   0.46%
Castro::apply_source_to_state()                 20    0.00845    0.01442    0.01679   0.46%
MultiFab::Saxpy()                               20   0.008425    0.01437    0.01674   0.46%
FabArray::setDomainBndry()                      41   0.006121    0.01213     0.0153   0.42%
Castro::check_for_nan()                         20   0.009808    0.01155    0.01395   0.38%
MultiFab::contains_nan()                        20   0.009788    0.01153    0.01393   0.38%
Castro::construct_old_source()                  50     0.0049    0.01007    0.01192   0.32%
Castro::construct_old_gravity_source()          10    0.00487   0.009997    0.01184   0.32%
Castro::Castro()                                 1   0.009892   0.009996    0.01008   0.27%
Castro::computeNewDt()                           9   0.007638   0.007651   0.007659   0.21%
Castro::enforce_min_density()                   62   0.005885   0.006659   0.007138   0.19%
Castro::initMFs()                                1   0.005453   0.005477   0.005518   0.15%
AmrLevel::AmrLevel(dm)                           1   0.004522   0.004546   0.004565   0.12%
StateData::define()                              4   0.004499   0.004529   0.004545   0.12%
Castro::swap_state_time_levels()                10   0.001687   0.003629   0.003969   0.11%
Castro::post_regrid()                            1    0.00152   0.001589   0.001649   0.04%
Castro::buildMetrics()                           1   0.001413   0.001441   0.001455   0.04%
Castro::finalize_do_advance()                   10  0.0005329   0.001182   0.001432   0.04%
Amr::InitAmr()                                   1  0.0002787  0.0002966  0.0003848   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.242e-05  0.0001012   0.000118   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.557e-06  9.571e-05  0.0001116   0.00%
Castro::enforce_consistent_e()                   1  7.703e-05  8.597e-05  9.487e-05   0.00%
FabArrayBase::getCPC()                          41  6.097e-05  7.461e-05  8.618e-05   0.00%
Castro::finalize_advance()                      10  5.104e-05  6.294e-05  7.047e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.356e-05  4.764e-05  6.953e-05   0.00%
FabArrayBase::CPC::define()                      2  2.259e-05  2.535e-05  3.072e-05   0.00%
Castro::enforce_speed_limit()                   62  2.019e-05  2.514e-05  2.912e-05   0.00%
Gravity::swapTimeLevels()                       10  4.769e-06  1.159e-05  1.543e-05   0.00%
Castro::create_source_corrector()               10  5.729e-06   1.05e-05  1.406e-05   0.00%
Gravity::set_mass_offset()                      10  6.838e-06  1.039e-05   1.27e-05   0.00%
Castro::FluxRegCrseInit                         10  6.616e-06  9.257e-06  1.244e-05   0.00%
Amr::initSubcycle()                              1  8.192e-06  8.514e-06  9.259e-06   0.00%
Castro::retry_advance_ctu()                     10  3.797e-06  5.594e-06  6.384e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.978e-06  2.261e-06   2.97e-06   0.00%
DistributionMapping::Distribute()                1  1.131e-06  1.244e-06  1.338e-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-33-g6f72de283c38) finalized