MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.07-16-gce0fb7412dff) initialized

Starting run at 05:39:45 UTC on 2022-07-27.
Successfully read inputs file ... 

Castro git describe: 22.07-13-g4177c4b30
AMReX git describe: 22.07-16-gce0fb7412
Microphysics git describe: 22.07-15-ga4952214

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

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

[STEP 1] Coarse TimeStep time: 0.355804246
[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.343457532
[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.342103421
[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.343647662
[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.353337908
[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.351859375
[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.337411652
[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.339263273
[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.340082274
[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.340570795
[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.010761056  seconds


Ending run at 05:39:49 UTC on 2022-07-27.
Run time = 3.663960541
Run time without initialization = 3.466260465

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



TinyProfiler total time across processes [min...avg...max]: 3.661 ... 3.663 ... 3.664

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1333     2.4194     2.5208  68.80%
Castro::do_advance_ctu()                        10    0.02962     0.1305     0.4189  11.43%
Gravity::make_radial_gravity()                  21     0.3493     0.3632     0.3883  10.60%
FabArray::ParallelCopy_finish()                 41     0.0436    0.06448     0.1248   3.41%
Castro::initData()                               1    0.06519    0.08432     0.1178   3.22%
Castro::estTimeStep()                           21    0.02681    0.06114    0.08013   2.19%
FabArray::setVal()                             143    0.06093    0.06845    0.07065   1.93%
Castro::reset_internal_energy(Fab)            1008    0.04787    0.04981    0.05216   1.42%
FabArray::ParallelCopy_nowait()                 41     0.0279    0.04542    0.05161   1.41%
StateData::FillBoundary(geom)                   41    0.03746    0.04212    0.04587   1.25%
Castro::normalize_species()                     62    0.03974    0.04201    0.04371   1.19%
FillPatchIterator::Initialize                   41     0.0244    0.03634    0.04072   1.11%
Gravity::interpolate_monopole_grav()            21    0.02668    0.02766    0.02837   0.77%
Castro::construct_new_gravity_source()          10    0.02022    0.02301    0.02391   0.65%
Castro::expand_state()                          10   0.009698    0.01808    0.02133   0.58%
Castro::computeTemp()                           63    0.01758    0.01876     0.0206   0.56%
Castro::initialize_do_advance()                 10    0.01022    0.01742    0.01955   0.53%
VisMF::Write(FabArray)                           2    0.01339    0.01408      0.017   0.46%
MultiFab::Saxpy()                               20   0.008607    0.01442     0.0164   0.45%
Amr::writePlotFile()                             2     0.0106    0.01344    0.01464   0.40%
MultiFab::contains_nan()                        20   0.009495    0.01156    0.01295   0.35%
Gravity::get_new_grav_vector()                  11   0.007392    0.01157    0.01295   0.35%
FabArray::setDomainBndry()                      41   0.004812    0.01042    0.01265   0.35%
Castro::construct_old_gravity_source()          10   0.004908    0.01019     0.0119   0.32%
Castro::initialize_advance()                    10   0.006598       0.01    0.01082   0.30%
Gravity::get_old_grav_vector()                  10   0.005415    0.00929    0.01068   0.29%
Castro::initMFs()                                1   0.006821    0.00796    0.01026   0.28%
StateData::define()                              4   0.005671    0.00656   0.008342   0.23%
Castro::construct_new_source()                  50    0.00475   0.006558   0.007256   0.20%
main()                                           1   0.004057   0.006205   0.007247   0.20%
Castro::enforce_min_density()                   62   0.005044    0.00602   0.006428   0.18%
Castro::swap_state_time_levels()                10   0.003333   0.004482   0.005231   0.14%
Amr::coarseTimeStep()                           10   0.000812   0.002046   0.003834   0.10%
Castro::buildMetrics()                           1   0.001656   0.001897   0.002403   0.07%
Castro::finalize_do_advance()                   10  0.0006628   0.001406   0.001675   0.05%
Amr::InitAmr()                                   1  0.0003025  0.0003757  0.0006487   0.02%
Amr::timeStep()                                 10  0.0001785  0.0002448  0.0005577   0.02%
DistributionMapping::LeastUsedCPUs()             1  8.298e-06  0.0003411   0.000428   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002648  0.0002971  0.0003221   0.01%
Castro::clean_state()                           62  0.0001094  0.0001576  0.0001797   0.00%
Castro::enforce_consistent_e()                   1  7.708e-05  0.0001092  0.0001694   0.00%
StateDataPhysBCFunct::()                        41  8.245e-05  0.0001385  0.0001578   0.00%
Castro::construct_old_source()                  50  3.297e-05  6.587e-05  8.662e-05   0.00%
FabArray::ParallelCopy()                        41  6.091e-05  7.503e-05  8.374e-05   0.00%
FillPatchSingleLevel                            41  4.553e-05  6.308e-05  8.084e-05   0.00%
Amr::FinalizeInit()                              1  3.994e-06  1.429e-05   8.01e-05   0.00%
Castro::do_new_sources()                        10  3.856e-05  6.536e-05  8.003e-05   0.00%
Amr::writeSmallPlotFile()                        1   2.88e-05  6.549e-05  7.721e-05   0.00%
Castro::do_old_sources()                        10  3.158e-05  6.201e-05  7.565e-05   0.00%
Castro::Castro()                                 1  5.287e-05  5.959e-05  7.376e-05   0.00%
Castro::apply_source_to_state()                 20  2.682e-05   4.74e-05  6.844e-05   0.00%
Castro::subcycle_advance_ctu()                  10  2.762e-05  4.479e-05  6.318e-05   0.00%
FabArrayBase::getCPC()                          41  3.781e-05  5.475e-05  6.225e-05   0.00%
Castro::finalize_advance()                      10  4.294e-05  5.035e-05  5.673e-05   0.00%
Castro::construct_old_gravity()                 10  2.828e-05  4.536e-05  5.207e-05   0.00%
Castro::construct_new_gravity()                 10  3.156e-05  4.398e-05  4.984e-05   0.00%
Castro::advance()                               10  2.773e-05  4.038e-05  4.803e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.389e-05  2.146e-05  3.803e-05   0.00%
Castro::enforce_speed_limit()                   62  2.197e-05  3.044e-05  3.437e-05   0.00%
Amr::defBaseLevel()                              1  1.833e-05  2.311e-05  3.366e-05   0.00%
FabArrayBase::CPC::define()                      2  2.163e-05  2.487e-05  2.945e-05   0.00%
Castro::check_for_nan()                         20  1.772e-05  2.194e-05  2.502e-05   0.00%
Gravity::swapTimeLevels()                       10  5.412e-06  1.143e-05   1.46e-05   0.00%
Castro::post_timestep()                         10  1.006e-05  1.289e-05   1.45e-05   0.00%
Amr::initSubcycle()                              1  8.393e-06   1.05e-05  1.448e-05   0.00%
Gravity::set_mass_offset()                      10  6.182e-06   9.56e-06  1.282e-05   0.00%
Castro::FluxRegCrseInit                         10  7.734e-06  1.033e-05  1.278e-05   0.00%
Castro::create_source_corrector()               10  7.028e-06  9.607e-06  1.254e-05   0.00%
Castro::computeNewDt()                           9  7.046e-06  9.076e-06  1.013e-05   0.00%
Amr::InitializeInit()                            1  2.143e-06  4.323e-06   9.43e-06   0.00%
Castro::retry_advance_ctu()                     10   3.97e-06  5.897e-06  7.694e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.807e-06   5.35e-06  6.951e-06   0.00%
Castro::computeInitialDt()                       2   3.03e-06  4.542e-06  5.924e-06   0.00%
Castro::post_init()                              1  2.424e-06  3.605e-06  4.721e-06   0.00%
Castro::post_regrid()                            1   1.59e-06  2.975e-06  4.406e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.947e-06  2.478e-06  2.981e-06   0.00%
Amr::init()                                      1  1.548e-06  1.934e-06   2.56e-06   0.00%
Amr::initialInit()                               1  1.101e-06  1.512e-06  2.094e-06   0.00%
DistributionMapping::Distribute()                1   1.06e-06  1.231e-06  1.416e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.661      3.663      3.664 100.00%
Amr::coarseTimeStep()                           10      3.448      3.448      3.448  94.11%
Amr::timeStep()                                 10      3.436      3.438      3.439  93.86%
Castro::advance()                               10      3.421      3.422      3.422  93.40%
Castro::subcycle_advance_ctu()                  10      3.348      3.351      3.356  91.59%
Castro::do_advance_ctu()                        10      3.348       3.35      3.356  91.59%
Castro::construct_ctu_hydro_source()            10      2.133      2.419      2.521  68.80%
Gravity::make_radial_gravity()                  21     0.3493     0.3632     0.3883  10.60%
Gravity::get_new_grav_vector()                  11     0.2379     0.2427     0.2454   6.70%
Castro::construct_old_gravity()                 10     0.1972     0.2106     0.2288   6.24%
Gravity::get_old_grav_vector()                  10     0.1964     0.2099     0.2282   6.23%
FillPatchIterator::Initialize                   41     0.1893     0.1991     0.2196   5.99%
Castro::construct_new_gravity()                 10     0.2018     0.2054     0.2075   5.66%
Amr::init()                                      1     0.1969     0.1972     0.1973   5.38%
FillPatchSingleLevel                            41     0.1367     0.1524     0.1904   5.20%
Castro::initialize_do_advance()                 10     0.1695     0.1801     0.1902   5.19%
Amr::initialInit()                               1     0.1801     0.1805     0.1808   4.93%
FabArray::ParallelCopy()                        41    0.09448     0.1101     0.1528   4.17%
Castro::expand_state()                          10     0.1269     0.1326     0.1412   3.85%
Amr::InitializeInit()                            1     0.0858     0.1073     0.1399   3.82%
Amr::defBaseLevel()                              1    0.08579     0.1073     0.1399   3.82%
FabArray::ParallelCopy_finish()                 41     0.0436    0.06448     0.1248   3.41%
Castro::clean_state()                           62     0.1101      0.116     0.1217   3.32%
Castro::initData()                               1    0.06881    0.08858     0.1214   3.31%
Amr::FinalizeInit()                              1    0.04019    0.07321    0.09499   2.59%
Castro::estTimeStep()                           21    0.02681    0.06114    0.08013   2.19%
Castro::initialize_advance()                    10    0.06504    0.07127    0.07345   2.00%
Castro::computeTemp()                           63    0.06601    0.06887    0.07309   1.99%
FabArray::setVal()                             143    0.06093    0.06845    0.07065   1.93%
Castro::do_new_sources()                        10     0.0535    0.06298      0.065   1.77%
Castro::computeInitialDt()                       2   0.001592     0.0341    0.05571   1.52%
Castro::reset_internal_energy(MultiFab)         63    0.04814    0.05011    0.05248   1.43%
Castro::reset_internal_energy(Fab)            1008    0.04787    0.04981    0.05216   1.42%
FabArray::ParallelCopy_nowait()                 41    0.02796     0.0455    0.05169   1.41%
Castro::do_old_sources()                        10    0.03251     0.0451    0.04891   1.33%
StateDataPhysBCFunct::()                        41    0.03754    0.04225    0.04603   1.26%
StateData::FillBoundary(geom)                   41    0.03746    0.04212    0.04587   1.25%
Castro::normalize_species()                     62    0.03974    0.04201    0.04371   1.19%
Castro::post_init()                              1    0.03688    0.03809    0.03862   1.05%
Castro::construct_new_source()                  50    0.02497    0.02957    0.03074   0.84%
Gravity::interpolate_monopole_grav()            21    0.02668    0.02766    0.02837   0.77%
Amr::writePlotFile()                             2     0.0271    0.02752    0.02802   0.76%
Castro::construct_new_gravity_source()          10    0.02022    0.02301    0.02391   0.65%
VisMF::Write(FabArray)                           2    0.01339    0.01408      0.017   0.46%
Castro::post_timestep()                         10    0.01412    0.01585    0.01659   0.45%
Castro::apply_source_to_state()                 20   0.008633    0.01447    0.01647   0.45%
MultiFab::Saxpy()                               20   0.008607    0.01442     0.0164   0.45%
Castro::Castro()                                 1    0.01044    0.01178    0.01446   0.39%
Castro::check_for_nan()                         20   0.009513    0.01158    0.01298   0.35%
MultiFab::contains_nan()                        20   0.009495    0.01156    0.01295   0.35%
FabArray::setDomainBndry()                      41   0.004812    0.01042    0.01265   0.35%
Castro::construct_old_source()                  50   0.004941    0.01026    0.01199   0.33%
Castro::construct_old_gravity_source()          10   0.004908    0.01019     0.0119   0.32%
Castro::initMFs()                                1   0.006821    0.00796    0.01026   0.28%
AmrLevel::AmrLevel(dm)                           1   0.005688   0.006581   0.008372   0.23%
StateData::define()                              4   0.005671    0.00656   0.008342   0.23%
Castro::computeNewDt()                           9   0.007352   0.007361   0.007366   0.20%
Castro::enforce_min_density()                   62   0.005044    0.00602   0.006428   0.18%
Castro::swap_state_time_levels()                10   0.003333   0.004482   0.005231   0.14%
Castro::post_regrid()                            1     0.0015   0.001922   0.003012   0.08%
Castro::buildMetrics()                           1   0.001769   0.002043   0.002621   0.07%
Castro::finalize_do_advance()                   10  0.0006628   0.001406   0.001675   0.05%
Amr::InitAmr()                                   1  0.0003118  0.0003862  0.0006632   0.02%
DistributionMapping::SFCProcessorMapDoIt()       1   1.35e-05  0.0003477  0.0004355   0.01%
DistributionMapping::LeastUsedCPUs()             1  8.298e-06  0.0003411   0.000428   0.01%
Castro::enforce_consistent_e()                   1  7.708e-05  0.0001092  0.0001694   0.00%
FabArrayBase::getCPC()                          41  6.447e-05  7.962e-05  8.684e-05   0.00%
Amr::writeSmallPlotFile()                        1   2.88e-05  6.549e-05  7.721e-05   0.00%
Castro::finalize_advance()                      10  5.269e-05  6.316e-05  6.788e-05   0.00%
Castro::enforce_speed_limit()                   62  2.197e-05  3.044e-05  3.437e-05   0.00%
FabArrayBase::CPC::define()                      2  2.163e-05  2.487e-05  2.945e-05   0.00%
Gravity::swapTimeLevels()                       10  5.412e-06  1.143e-05   1.46e-05   0.00%
Amr::initSubcycle()                              1  8.393e-06   1.05e-05  1.448e-05   0.00%
Gravity::set_mass_offset()                      10  6.182e-06   9.56e-06  1.282e-05   0.00%
Castro::FluxRegCrseInit                         10  7.734e-06  1.033e-05  1.278e-05   0.00%
Castro::create_source_corrector()               10  7.028e-06  9.607e-06  1.254e-05   0.00%
Castro::retry_advance_ctu()                     10   3.97e-06  5.897e-06  7.694e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.947e-06  2.478e-06  2.981e-06   0.00%
DistributionMapping::Distribute()                1   1.06e-06  1.231e-06  1.416e-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.07-16-gce0fb7412dff) finalized