MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.04-6-g917afc0e1896) initialized

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

Castro git describe: 22.04-1-gabf8dcbcc
AMReX git describe: 22.04-6-g917afc0e1
Microphysics git describe: 22.04-1-gef526ccd

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

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

[STEP 1] Coarse TimeStep time: 0.338149498
[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.335837972
[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.314073205
[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.313752313
[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.315359494
[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.341861015
[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.299380819
[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.294319608
[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.294106361
[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.294026739
[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.009333334  seconds


Ending run at 05:37:07 UTC on 2022-04-05.
Run time = 3.338224997
Run time without initialization = 3.153739768

  Average number of zones advanced per microsecond: 0.831
  Average number of zones advanced per microsecond per rank: 0.104



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

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1382     2.1812     2.2447  67.24%
Gravity::make_radial_gravity()                  21     0.3333     0.3444     0.3626  10.86%
Castro::initData()                               1    0.03782     0.0806     0.1245   3.73%
MultiFab::min()                                 10    0.01143    0.07772     0.1241   3.72%
Castro::estTimeStep()                           21    0.01766    0.06696     0.1064   3.19%
FabArray::setVal()                             143    0.06824    0.07278    0.07755   2.32%
FabArray::ParallelCopy_finish()                 41    0.03362    0.04148    0.06387   1.91%
Castro::reset_internal_energy()                 63    0.04834    0.04994     0.0516   1.55%
StateData::FillBoundary(geom)                   41    0.03953    0.04205    0.04518   1.35%
Castro::normalize_species()                     62    0.04259    0.04333    0.04413   1.32%
FabArray::ParallelCopy_nowait()                 41    0.03592    0.04126    0.04311   1.29%
FillPatchIterator::Initialize                   41    0.02378    0.03119    0.03437   1.03%
Gravity::interpolate_monopole_grav()            21    0.02763    0.02789    0.02813   0.84%
Castro::construct_new_gravity_source()          10    0.02112    0.02365    0.02589   0.78%
Castro::do_advance_ctu()                        10    0.01764    0.02031    0.02451   0.73%
Castro::expand_state()                          10    0.01134    0.01896     0.0217   0.65%
Castro::computeTemp()                           63    0.01769    0.01882    0.01963   0.59%
Castro::initialize_do_advance()                 10    0.01067    0.01642      0.019   0.57%
MultiFab::Saxpy()                               20     0.0139    0.01526    0.01608   0.48%
Castro::initialize_advance()                    10    0.01074    0.01242    0.01438   0.43%
VisMF::Write(FabArray)                           2    0.01159    0.01201    0.01401   0.42%
Gravity::get_new_grav_vector()                  11   0.009664    0.01122    0.01319   0.40%
MultiFab::contains_nan()                        20    0.01069    0.01139    0.01214   0.36%
FabArray::setDomainBndry()                      41   0.007039    0.01037     0.0117   0.35%
Castro::construct_old_gravity_source()          10   0.007218    0.01099    0.01169   0.35%
Amr::writePlotFile()                             2    0.00843    0.01051    0.01094   0.33%
Gravity::get_old_grav_vector()                  10   0.006868   0.009203    0.01059   0.32%
Amr::defBaseLevel()                              1   0.006742   0.007284   0.007554   0.23%
Castro::construct_new_source()                  50   0.006064   0.006476   0.006711   0.20%
Castro::enforce_min_density()                   62   0.005789   0.006166   0.006622   0.20%
StateData::define()                              4   0.004385   0.004747   0.004883   0.15%
Castro::swap_state_time_levels()                10   0.002947   0.003815   0.004226   0.13%
main()                                           1   0.003298   0.003786   0.004065   0.12%
Amr::coarseTimeStep()                           10  0.0003111  0.0005773   0.001003   0.03%
Castro::finalize_do_advance()                   10  0.0004594  0.0008194  0.0009632   0.03%
Amr::timeStep()                                 10  0.0001608  0.0002278  0.0004571   0.01%
Amr::InitAmr()                                   1  0.0002816  0.0003119  0.0004038   0.01%
Castro::clean_state()                           62  0.0001231  0.0001411  0.0001669   0.00%
DistributionMapping::LeastUsedCPUs()             1  6.811e-06  0.0001175  0.0001615   0.00%
StateDataPhysBCFunct::()                        41  0.0001198  0.0001312  0.0001473   0.00%
Castro::enforce_consistent_e()                   1  7.918e-05  8.774e-05   9.97e-05   0.00%
Castro::construct_old_source()                  50  6.738e-05  8.387e-05  9.775e-05   0.00%
FabArray::ParallelCopy()                        41  6.807e-05  7.411e-05  8.079e-05   0.00%
FabArrayBase::getCPC()                          41  5.004e-05  5.955e-05  7.908e-05   0.00%
FillPatchSingleLevel                            41   6.03e-05  6.783e-05  7.851e-05   0.00%
Amr::FinalizeInit()                              1  3.683e-06  1.313e-05   7.28e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.461e-05  5.235e-05  7.255e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.862e-05  5.661e-05   7.24e-05   0.00%
Castro::do_old_sources()                        10  5.032e-05  6.213e-05  7.167e-05   0.00%
Castro::construct_old_gravity()                 10  5.617e-05  6.149e-05  6.948e-05   0.00%
Castro::do_new_sources()                        10  5.568e-05  6.137e-05  6.634e-05   0.00%
Castro::apply_source_to_state()                 20  4.226e-05  5.262e-05  6.534e-05   0.00%
Castro::finalize_advance()                      10  4.898e-05  5.341e-05  6.035e-05   0.00%
Castro::construct_new_gravity()                 10  3.936e-05  5.054e-05  5.937e-05   0.00%
Castro::advance()                               10  3.905e-05  4.315e-05   5.29e-05   0.00%
Castro::enforce_speed_limit()                   62  2.586e-05  3.304e-05  3.886e-05   0.00%
Gravity::swapTimeLevels()                       10  1.408e-05  1.717e-05  3.085e-05   0.00%
Castro::check_for_nan()                         20  2.193e-05  2.643e-05      3e-05   0.00%
FabArrayBase::CPC::define()                      2  2.371e-05  2.567e-05  2.895e-05   0.00%
Castro::create_source_corrector()               10  1.561e-05    1.8e-05  2.182e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.196e-05  1.451e-05   1.67e-05   0.00%
Castro::post_timestep()                         10   8.66e-06   9.68e-06  1.089e-05   0.00%
Amr::initSubcycle()                              1  8.445e-06  9.461e-06  1.018e-05   0.00%
Gravity::set_mass_offset()                      10  7.356e-06  8.342e-06   9.97e-06   0.00%
Castro::computeNewDt()                           9  6.571e-06  8.128e-06  9.339e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  4.222e-06  4.875e-06  7.613e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.418e-06   6.43e-06  6.803e-06   0.00%
Castro::retry_advance_ctu()                     10  5.319e-06  5.898e-06   6.19e-06   0.00%
Amr::InitializeInit()                            1  2.327e-06  2.923e-06  5.834e-06   0.00%
Castro::post_init()                              1  2.734e-06  4.022e-06  5.367e-06   0.00%
Castro::computeInitialDt()                       2  2.966e-06  3.747e-06  4.587e-06   0.00%
Castro::post_regrid()                            1  1.987e-06  2.732e-06  3.804e-06   0.00%
Amr::init()                                      1  1.869e-06   2.38e-06  3.021e-06   0.00%
Amr::initialInit()                               1   1.03e-06   1.42e-06  1.758e-06   0.00%
DistributionMapping::Distribute()                1   9.88e-07   1.08e-06  1.199e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.338      3.338      3.338 100.00%
Amr::coarseTimeStep()                           10       3.14       3.14       3.14  94.07%
Amr::timeStep()                                 10      3.131      3.132      3.132  93.82%
Castro::advance()                               10      3.115      3.115      3.115  93.33%
Castro::subcycle_advance_ctu()                  10      3.036      3.039      3.042  91.11%
Castro::do_advance_ctu()                        10      3.036      3.039      3.042  91.11%
Castro::construct_ctu_hydro_source()            10      2.138      2.181      2.245  67.24%
Gravity::make_radial_gravity()                  21     0.3333     0.3444     0.3626  10.86%
Gravity::get_new_grav_vector()                  11     0.2231      0.225     0.2262   6.78%
Castro::construct_old_gravity()                 10     0.1982     0.2057     0.2214   6.63%
Gravity::get_old_grav_vector()                  10     0.1973     0.2049     0.2208   6.61%
Castro::construct_new_gravity()                 10        0.2     0.2013     0.2024   6.06%
Amr::init()                                      1     0.1839      0.184     0.1841   5.51%
FillPatchIterator::Initialize                   41     0.1586     0.1667     0.1717   5.14%
Amr::initialInit()                               1     0.1708     0.1709     0.1711   5.12%
Castro::initialize_do_advance()                 10      0.142     0.1567     0.1624   4.86%
Amr::InitializeInit()                            1    0.05505    0.09935     0.1439   4.31%
Amr::defBaseLevel()                              1    0.05504    0.09934     0.1439   4.31%
FillPatchSingleLevel                            41     0.1202     0.1251     0.1409   4.22%
Castro::initData()                               1    0.04198    0.08463     0.1284   3.85%
MultiFab::min()                                 10    0.01143    0.07772     0.1241   3.72%
Castro::clean_state()                           62     0.1142     0.1175     0.1207   3.62%
Amr::FinalizeInit()                              1    0.02691     0.0716     0.1159   3.47%
Castro::expand_state()                          10     0.1013     0.1091     0.1136   3.40%
Castro::estTimeStep()                           21    0.01766    0.06696     0.1064   3.19%
FabArray::ParallelCopy()                        41    0.07619     0.0829    0.09998   2.99%
Castro::computeInitialDt()                       2   0.001711     0.0462    0.09055   2.71%
Castro::initialize_advance()                    10    0.07365    0.07664    0.07963   2.39%
FabArray::setVal()                             143    0.06824    0.07278    0.07755   2.32%
Castro::computeTemp()                           63    0.06603    0.06876    0.07123   2.13%
Castro::do_new_sources()                        10    0.06004    0.06309    0.06774   2.03%
FabArray::ParallelCopy_finish()                 41    0.03362    0.04148    0.06387   1.91%
Castro::reset_internal_energy()                 63    0.04834    0.04994     0.0516   1.55%
Castro::do_old_sources()                        10    0.04255    0.04742     0.0489   1.46%
StateDataPhysBCFunct::()                        41    0.03968    0.04218     0.0453   1.36%
StateData::FillBoundary(geom)                   41    0.03953    0.04205    0.04518   1.35%
Castro::normalize_species()                     62    0.04259    0.04333    0.04413   1.32%
FabArray::ParallelCopy_nowait()                 41    0.03603    0.04135    0.04319   1.29%
Castro::construct_new_source()                  50    0.02719    0.03013    0.03258   0.98%
Gravity::interpolate_monopole_grav()            21    0.02763    0.02789    0.02813   0.84%
Castro::construct_new_gravity_source()          10    0.02112    0.02365    0.02589   0.78%
Castro::post_init()                              1     0.0245    0.02472     0.0248   0.74%
Amr::writePlotFile()                             2    0.02244    0.02252    0.02272   0.68%
Castro::post_timestep()                         10    0.01558    0.01597    0.01632   0.49%
Castro::apply_source_to_state()                 20    0.01394    0.01531    0.01614   0.48%
MultiFab::Saxpy()                               20     0.0139    0.01526    0.01608   0.48%
VisMF::Write(FabArray)                           2    0.01159    0.01201    0.01401   0.42%
Castro::check_for_nan()                         20    0.01071    0.01142    0.01216   0.36%
MultiFab::contains_nan()                        20    0.01069    0.01139    0.01214   0.36%
Castro::construct_old_source()                  50   0.007285    0.01108    0.01178   0.35%
FabArray::setDomainBndry()                      41   0.007039    0.01037     0.0117   0.35%
Castro::construct_old_gravity_source()          10   0.007218    0.01099    0.01169   0.35%
Castro::computeNewDt()                           9   0.006863   0.007338   0.007925   0.24%
Castro::enforce_min_density()                   62   0.005789   0.006166   0.006622   0.20%
AmrLevel::AmrLevel(dm)                           1   0.004397   0.004762   0.004899   0.15%
StateData::define()                              4   0.004385   0.004747   0.004883   0.15%
Castro::swap_state_time_levels()                10   0.002947   0.003815   0.004226   0.13%
Castro::post_regrid()                            1   0.001547   0.001593   0.001639   0.05%
Castro::finalize_do_advance()                   10  0.0004594  0.0008194  0.0009632   0.03%
Amr::InitAmr()                                   1  0.0002904  0.0003214  0.0004135   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.234e-05  0.0001234  0.0001676   0.01%
DistributionMapping::LeastUsedCPUs()             1  6.811e-06  0.0001175  0.0001615   0.00%
FabArrayBase::getCPC()                          41  7.491e-05  8.522e-05  0.0001056   0.00%
Castro::enforce_consistent_e()                   1  7.918e-05  8.774e-05   9.97e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.461e-05  5.235e-05  7.255e-05   0.00%
Castro::finalize_advance()                      10  5.574e-05  5.984e-05  6.703e-05   0.00%
Castro::enforce_speed_limit()                   62  2.586e-05  3.304e-05  3.886e-05   0.00%
Gravity::swapTimeLevels()                       10  1.408e-05  1.717e-05  3.085e-05   0.00%
FabArrayBase::CPC::define()                      2  2.371e-05  2.567e-05  2.895e-05   0.00%
Castro::create_source_corrector()               10  1.561e-05    1.8e-05  2.182e-05   0.00%
Amr::initSubcycle()                              1  8.445e-06  9.461e-06  1.018e-05   0.00%
Gravity::set_mass_offset()                      10  7.356e-06  8.342e-06   9.97e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.418e-06   6.43e-06  6.803e-06   0.00%
Castro::retry_advance_ctu()                     10  5.319e-06  5.898e-06   6.19e-06   0.00%
DistributionMapping::Distribute()                1   9.88e-07   1.08e-06  1.199e-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.04-6-g917afc0e1896) finalized