MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.03-36-g75e2e7d09e68) initialized

Starting run at 05:37:12 UTC on 2022-04-01.
Successfully read inputs file ... 

Castro git describe: 22.02-19-ga92ac477d
AMReX git describe: 22.03-36-g75e2e7d09
Microphysics git describe: 22.03-14-g4f1304ed

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

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

[STEP 1] Coarse TimeStep time: 0.333376335
[STEP 1] FAB kilobyte spread across MPI nodes: [58480 ... 58480]

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

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

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

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

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

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

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

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

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

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

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


Ending run at 05:37:16 UTC on 2022-04-01.
Run time = 3.377393297
Run time without initialization = 3.183143155

  Average number of zones advanced per microsecond: 0.824
  Average number of zones advanced per microsecond per rank: 0.103



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

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1461     2.1850     2.2282  65.97%
Gravity::make_radial_gravity()                  21     0.3345     0.3456     0.3553  10.52%
Castro::initData()                               1    0.03988    0.08276     0.1343   3.98%
Castro::estTimeStep()                           21    0.01748    0.07581     0.1214   3.59%
MultiFab::min()                                 10    0.01182    0.05789    0.09927   2.94%
FabArray::setVal()                             143    0.07074      0.074     0.0775   2.29%
FabArray::ParallelCopy_finish()                 41    0.03395    0.04092    0.05775   1.71%
Castro::reset_internal_energy()                 63    0.04851    0.05017    0.05211   1.54%
Amr::writePlotFile()                             2    0.04467    0.04664    0.04719   1.40%
StateData::FillBoundary(geom)                   41    0.03923    0.04166    0.04533   1.34%
Castro::normalize_species()                     62    0.04246    0.04365    0.04529   1.34%
FabArray::ParallelCopy_nowait()                 41    0.04029    0.04216    0.04443   1.32%
FillPatchIterator::Initialize                   41    0.02393    0.03169    0.03476   1.03%
Gravity::interpolate_monopole_grav()            21    0.02764    0.02804     0.0286   0.85%
Castro::construct_new_gravity_source()          10    0.02308    0.02473    0.02682   0.79%
Castro::do_advance_ctu()                        10    0.01925    0.02131    0.02458   0.73%
Castro::expand_state()                          10    0.01181    0.01843    0.02077   0.61%
Castro::computeTemp()                           63    0.01837    0.01896    0.01986   0.59%
Castro::initialize_do_advance()                 10    0.01266    0.01662    0.01867   0.55%
MultiFab::Saxpy()                               20    0.01495      0.016    0.01663   0.49%
Castro::initialize_advance()                    10    0.01074    0.01252    0.01456   0.43%
VisMF::Write(FabArray)                           2    0.01146    0.01199    0.01402   0.42%
Gravity::get_new_grav_vector()                  11    0.01033    0.01139    0.01357   0.40%
MultiFab::contains_nan()                        20    0.01122    0.01212    0.01323   0.39%
Castro::construct_old_gravity_source()          10   0.009245     0.0116    0.01233   0.36%
FabArray::setDomainBndry()                      41   0.006909    0.01045     0.0119   0.35%
Gravity::get_old_grav_vector()                  10   0.008033   0.009371    0.01064   0.31%
Amr::defBaseLevel()                              1   0.006606   0.007164   0.007682   0.23%
Castro::construct_new_source()                  50   0.006406   0.006721   0.007013   0.21%
Castro::enforce_min_density()                   62   0.005874   0.006174   0.006472   0.19%
StateData::define()                              4   0.004345   0.004733   0.005092   0.15%
main()                                           1   0.003252   0.003695    0.00395   0.12%
Castro::swap_state_time_levels()                10   0.002935   0.003749    0.00389   0.12%
Amr::coarseTimeStep()                           10  0.0003101  0.0005983   0.001195   0.04%
Castro::finalize_do_advance()                   10  0.0004972  0.0008232   0.000928   0.03%
Amr::timeStep()                                 10  0.0001649  0.0002251  0.0004563   0.01%
Amr::InitAmr()                                   1   0.000258  0.0003067  0.0004267   0.01%
DistributionMapping::LeastUsedCPUs()             1  8.678e-06  0.0001279  0.0001855   0.01%
Castro::clean_state()                           62  0.0001291  0.0001466  0.0001623   0.00%
StateDataPhysBCFunct::()                        41  0.0001268  0.0001327  0.0001426   0.00%
Amr::FinalizeInit()                              1  3.808e-06  1.834e-05  0.0001166   0.00%
Castro::enforce_consistent_e()                   1  8.292e-05  8.843e-05  9.641e-05   0.00%
Castro::construct_old_source()                  50  7.226e-05  8.446e-05  9.619e-05   0.00%
FillPatchSingleLevel                            41  6.566e-05  7.091e-05  8.179e-05   0.00%
Castro::do_old_sources()                        10  5.594e-05  6.597e-05  7.603e-05   0.00%
FabArray::ParallelCopy()                        41  6.317e-05  7.042e-05  7.526e-05   0.00%
FabArrayBase::getCPC()                          41  4.753e-05  5.807e-05  7.335e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.491e-05  4.525e-05  7.287e-05   0.00%
Castro::construct_old_gravity()                 10  5.424e-05  6.134e-05  7.211e-05   0.00%
Castro::do_new_sources()                        10  5.494e-05  6.189e-05  6.886e-05   0.00%
Castro::subcycle_advance_ctu()                  10  4.009e-05  5.528e-05  6.839e-05   0.00%
Castro::construct_new_gravity()                 10  3.718e-05  5.057e-05  6.333e-05   0.00%
Castro::apply_source_to_state()                 20  4.508e-05   5.05e-05  5.824e-05   0.00%
Castro::finalize_advance()                      10  4.757e-05  5.299e-05  5.666e-05   0.00%
Castro::advance()                               10  3.442e-05  4.017e-05  4.501e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.386e-05  2.599e-05  3.762e-05   0.00%
Castro::enforce_speed_limit()                   62  2.926e-05  3.218e-05  3.746e-05   0.00%
FabArrayBase::CPC::define()                      2   2.29e-05  2.604e-05  3.304e-05   0.00%
Castro::check_for_nan()                         20  2.477e-05  2.763e-05  3.191e-05   0.00%
Castro::post_init()                              1  2.941e-06  6.906e-06  2.877e-05   0.00%
Castro::create_source_corrector()               10  1.324e-05  1.821e-05  2.438e-05   0.00%
Gravity::swapTimeLevels()                       10  1.181e-05  1.434e-05  1.599e-05   0.00%
Amr::initSubcycle()                              1  8.659e-06   9.72e-06  1.129e-05   0.00%
Amr::InitializeInit()                            1  2.396e-06  4.582e-06   1.08e-05   0.00%
Castro::post_timestep()                         10  8.792e-06  9.643e-06  1.019e-05   0.00%
Gravity::set_mass_offset()                      10  7.357e-06  8.072e-06  9.591e-06   0.00%
Castro::computeNewDt()                           9  6.907e-06  7.727e-06  9.232e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.051e-06  6.487e-06  7.659e-06   0.00%
Castro::retry_advance_ctu()                     10  4.989e-06  6.267e-06  7.445e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  4.216e-06   4.64e-06  5.667e-06   0.00%
Castro::computeInitialDt()                       2  2.739e-06  3.958e-06  5.084e-06   0.00%
Castro::post_regrid()                            1  1.662e-06   2.63e-06  3.832e-06   0.00%
Amr::init()                                      1  1.857e-06   2.25e-06  2.471e-06   0.00%
Amr::initialInit()                               1  1.352e-06  1.719e-06  2.017e-06   0.00%
DistributionMapping::Distribute()                1   9.17e-07  1.055e-06  1.388e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.377      3.377      3.377 100.00%
Amr::coarseTimeStep()                           10      3.134      3.134      3.134  92.78%
Amr::timeStep()                                 10      3.124      3.124      3.125  92.52%
Castro::advance()                               10      3.108      3.108      3.108  92.03%
Castro::subcycle_advance_ctu()                  10      3.029      3.031      3.034  89.84%
Castro::do_advance_ctu()                        10      3.029      3.031      3.034  89.84%
Castro::construct_ctu_hydro_source()            10      2.146      2.185      2.228  65.97%
Gravity::make_radial_gravity()                  21     0.3345     0.3456     0.3553  10.52%
Gravity::get_new_grav_vector()                  11      0.225     0.2267      0.228   6.75%
Castro::construct_old_gravity()                 10     0.1972     0.2058     0.2144   6.35%
Gravity::get_old_grav_vector()                  10     0.1963      0.205     0.2136   6.32%
Castro::construct_new_gravity()                 10     0.2022     0.2031     0.2036   6.03%
Amr::init()                                      1     0.1937     0.1938     0.1938   5.74%
Amr::initialInit()                               1     0.1807     0.1809     0.1812   5.36%
FillPatchIterator::Initialize                   41     0.1607     0.1672     0.1707   5.06%
Castro::initialize_do_advance()                 10     0.1471     0.1571     0.1623   4.81%
Amr::InitializeInit()                            1    0.05889     0.1012     0.1538   4.55%
Amr::defBaseLevel()                              1    0.05889     0.1012     0.1538   4.55%
FillPatchSingleLevel                            41     0.1198     0.1251     0.1386   4.10%
Castro::initData()                               1    0.04363     0.0868     0.1385   4.10%
Castro::clean_state()                           62      0.115     0.1182     0.1226   3.63%
Amr::FinalizeInit()                              1    0.02714    0.07971     0.1219   3.61%
Castro::estTimeStep()                           21    0.01748    0.07581     0.1214   3.59%
Castro::expand_state()                          10     0.1024     0.1091      0.113   3.35%
MultiFab::min()                                 10    0.01182    0.05789    0.09927   2.94%
FabArray::ParallelCopy()                        41    0.07699    0.08323    0.09821   2.91%
Castro::computeInitialDt()                       2   0.001748    0.05432    0.09655   2.86%
Castro::initialize_advance()                    10     0.0738    0.07672    0.07904   2.34%
FabArray::setVal()                             143    0.07074      0.074     0.0775   2.29%
Castro::computeTemp()                           63    0.06724    0.06913    0.07197   2.13%
Castro::do_new_sources()                        10    0.06133    0.06568    0.06985   2.07%
Amr::writePlotFile()                             2    0.05846    0.05863    0.05883   1.74%
FabArray::ParallelCopy_finish()                 41    0.03395    0.04092    0.05775   1.71%
Castro::reset_internal_energy()                 63    0.04851    0.05017    0.05211   1.54%
Castro::do_old_sources()                        10    0.04726     0.0491       0.05   1.48%
StateDataPhysBCFunct::()                        41    0.03936     0.0418    0.04546   1.35%
StateData::FillBoundary(geom)                   41    0.03923    0.04166    0.04533   1.34%
Castro::normalize_species()                     62    0.04246    0.04365    0.04529   1.34%
FabArray::ParallelCopy_nowait()                 41    0.04039    0.04224     0.0445   1.32%
Castro::construct_new_source()                  50    0.02949    0.03146    0.03376   1.00%
Gravity::interpolate_monopole_grav()            21    0.02764    0.02804     0.0286   0.85%
Castro::construct_new_gravity_source()          10    0.02308    0.02473    0.02682   0.79%
Castro::post_init()                              1    0.02424    0.02466    0.02488   0.74%
Castro::apply_source_to_state()                 20      0.015    0.01605    0.01668   0.49%
MultiFab::Saxpy()                               20    0.01495      0.016    0.01663   0.49%
Castro::post_timestep()                         10    0.01584    0.01603     0.0164   0.49%
VisMF::Write(FabArray)                           2    0.01146    0.01199    0.01402   0.42%
Castro::check_for_nan()                         20    0.01125    0.01215    0.01326   0.39%
MultiFab::contains_nan()                        20    0.01122    0.01212    0.01323   0.39%
Castro::construct_old_source()                  50   0.009317    0.01168    0.01242   0.37%
Castro::construct_old_gravity_source()          10   0.009245     0.0116    0.01233   0.36%
FabArray::setDomainBndry()                      41   0.006909    0.01045     0.0119   0.35%
Castro::computeNewDt()                           9   0.006961   0.007646   0.008204   0.24%
Castro::enforce_min_density()                   62   0.005874   0.006174   0.006472   0.19%
AmrLevel::AmrLevel(dm)                           1   0.004373   0.004759   0.005129   0.15%
StateData::define()                              4   0.004345   0.004733   0.005092   0.15%
Castro::swap_state_time_levels()                10   0.002935   0.003749    0.00389   0.12%
Castro::post_regrid()                            1   0.001506   0.001633   0.001988   0.06%
Castro::finalize_do_advance()                   10  0.0004972  0.0008232   0.000928   0.03%
Amr::InitAmr()                                   1  0.0002673  0.0003164  0.0004367   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.434e-05  0.0001336  0.0001923   0.01%
DistributionMapping::LeastUsedCPUs()             1  8.678e-06  0.0001279  0.0001855   0.01%
FabArrayBase::getCPC()                          41    7.1e-05  8.411e-05  0.0001015   0.00%
Castro::enforce_consistent_e()                   1  8.292e-05  8.843e-05  9.641e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.491e-05  4.525e-05  7.287e-05   0.00%
Castro::finalize_advance()                      10  5.337e-05  5.948e-05  6.393e-05   0.00%
Castro::enforce_speed_limit()                   62  2.926e-05  3.218e-05  3.746e-05   0.00%
FabArrayBase::CPC::define()                      2   2.29e-05  2.604e-05  3.304e-05   0.00%
Castro::create_source_corrector()               10  1.324e-05  1.821e-05  2.438e-05   0.00%
Gravity::swapTimeLevels()                       10  1.181e-05  1.434e-05  1.599e-05   0.00%
Amr::initSubcycle()                              1  8.659e-06   9.72e-06  1.129e-05   0.00%
Gravity::set_mass_offset()                      10  7.357e-06  8.072e-06  9.591e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.051e-06  6.487e-06  7.659e-06   0.00%
Castro::retry_advance_ctu()                     10  4.989e-06  6.267e-06  7.445e-06   0.00%
DistributionMapping::Distribute()                1   9.17e-07  1.055e-06  1.388e-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.03-36-g75e2e7d09e68) finalized