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

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

Castro git describe: 22.06-5-gbb91df8fe
AMReX git describe: 22.06-8-g8a1c76c0a
Microphysics git describe: 22.06-1-gb8e427c1

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

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

[STEP 1] Coarse TimeStep time: 0.357956355
[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.336110798
[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.333597971
[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.333444384
[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.345110485
[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.339880052
[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.331550005
[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.329870854
[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.330616672
[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.331118451
[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.009618875  seconds


Ending run at 16:25:42 UTC on 2022-06-07.
Run time = 3.589240766
Run time without initialization = 3.384984586

  Average number of zones advanced per microsecond: 0.774
  Average number of zones advanced per microsecond per rank: 0.097



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

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.0956     2.3342     2.4352  67.85%
Gravity::make_radial_gravity()                  21     0.3513     0.3622     0.3875  10.80%
Castro::do_advance_ctu()                        10    0.03981     0.1432     0.3838  10.69%
Castro::initData()                               1    0.03764    0.09145     0.1344   3.74%
Castro::estTimeStep()                           21    0.02888    0.07235     0.1266   3.53%
FabArray::ParallelCopy_finish()                 41    0.04096    0.06055     0.1202   3.35%
FabArray::setVal()                             143    0.05826    0.06839    0.07142   1.99%
Castro::reset_internal_energy(Fab)            1008    0.04834     0.0501    0.05223   1.46%
FabArray::ParallelCopy_nowait()                 41    0.02588    0.04316     0.0493   1.37%
StateData::FillBoundary(geom)                   41    0.03694    0.04195    0.04655   1.30%
Castro::normalize_species()                     62    0.04128    0.04229     0.0429   1.20%
FillPatchIterator::Initialize                   41    0.02042    0.03207    0.03645   1.02%
Gravity::interpolate_monopole_grav()            21    0.02687    0.02799    0.02895   0.81%
Castro::construct_new_gravity_source()          10    0.02035    0.02348    0.02491   0.69%
Castro::expand_state()                          10   0.009751    0.01859    0.02251   0.63%
Castro::computeTemp()                           63    0.01806    0.01904    0.01975   0.55%
Castro::initialize_do_advance()                 10      0.009    0.01644    0.01831   0.51%
MultiFab::Saxpy()                               20   0.008655    0.01454    0.01631   0.45%
FabArray::setDomainBndry()                      41    0.00611    0.01194    0.01499   0.42%
VisMF::Write(FabArray)                           2    0.01107    0.01192    0.01444   0.40%
Amr::writePlotFile()                             2   0.009167    0.01172    0.01269   0.35%
Gravity::get_new_grav_vector()                  11   0.007727    0.01119    0.01246   0.35%
MultiFab::contains_nan()                        20   0.009366    0.01103    0.01192   0.33%
Castro::construct_old_gravity_source()          10   0.004977    0.01011    0.01178   0.33%
Gravity::get_old_grav_vector()                  10   0.005723    0.00924    0.01083   0.30%
Castro::initialize_advance()                    10   0.006251   0.009846    0.01043   0.29%
Castro::initMFs()                                1   0.005452   0.006456   0.008287   0.23%
Castro::construct_new_source()                  50   0.004904   0.006531   0.007141   0.20%
StateData::define()                              4   0.004524    0.00529   0.006744   0.19%
Castro::enforce_min_density()                   62   0.005417   0.006115   0.006603   0.18%
main()                                           1   0.003717   0.005012   0.005472   0.15%
Castro::swap_state_time_levels()                10    0.00282   0.003663     0.0042   0.12%
Amr::coarseTimeStep()                           10  0.0008384   0.001584   0.003151   0.09%
Castro::buildMetrics()                           1   0.001302   0.001506   0.001875   0.05%
Castro::finalize_do_advance()                   10   0.000534   0.001077   0.001261   0.04%
Amr::InitAmr()                                   1  0.0002769   0.000359  0.0005281   0.01%
Amr::timeStep()                                 10  0.0001675  0.0002206  0.0004533   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002867   0.000303  0.0003201   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.597e-06  0.0002141    0.00031   0.01%
Castro::clean_state()                           62   0.000132  0.0001626  0.0001738   0.00%
Castro::enforce_consistent_e()                   1  7.769e-05  0.0001032  0.0001583   0.00%
StateDataPhysBCFunct::()                        41  0.0001205  0.0001405  0.0001539   0.00%
FabArray::ParallelCopy()                        41  7.799e-05  8.736e-05  9.668e-05   0.00%
Castro::construct_old_source()                  50  3.945e-05   7.74e-05  9.291e-05   0.00%
FillPatchSingleLevel                            41  6.708e-05  7.463e-05  8.255e-05   0.00%
Castro::do_new_sources()                        10  4.101e-05  6.626e-05  8.086e-05   0.00%
Castro::do_old_sources()                        10  4.099e-05    6.6e-05  7.927e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.359e-05  5.351e-05   7.63e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.434e-05  5.514e-05  7.189e-05   0.00%
Amr::FinalizeInit()                              1  3.951e-06  1.341e-05  7.129e-05   0.00%
Castro::Castro()                                 1  4.891e-05  6.043e-05  6.785e-05   0.00%
FabArrayBase::getCPC()                          41  5.835e-05  6.166e-05  6.752e-05   0.00%
Castro::construct_old_gravity()                 10  3.834e-05  5.313e-05  6.209e-05   0.00%
Castro::finalize_advance()                      10  4.898e-05  5.127e-05   5.42e-05   0.00%
Castro::apply_source_to_state()                 20  3.938e-05  4.573e-05  5.248e-05   0.00%
Castro::construct_new_gravity()                 10  3.392e-05  4.374e-05  5.035e-05   0.00%
Castro::advance()                               10  3.217e-05  4.055e-05   4.59e-05   0.00%
Amr::defBaseLevel()                              1  2.764e-05  3.091e-05  3.577e-05   0.00%
FabArrayBase::CPC::define()                      2  2.359e-05  2.795e-05  3.561e-05   0.00%
Castro::enforce_speed_limit()                   62  2.359e-05  2.764e-05    3.2e-05   0.00%
Castro::check_for_nan()                         20  2.061e-05  2.508e-05  2.726e-05   0.00%
Castro::post_timestep()                         10   1.06e-05  1.781e-05  2.274e-05   0.00%
AmrLevel::AmrLevel(dm)                           1   1.47e-05  1.674e-05  2.242e-05   0.00%
Gravity::swapTimeLevels()                       10  5.192e-06  1.179e-05  1.672e-05   0.00%
Amr::initSubcycle()                              1  8.293e-06  1.083e-05  1.649e-05   0.00%
Castro::create_source_corrector()               10  6.281e-06  1.175e-05  1.544e-05   0.00%
Castro::post_init()                              1  8.131e-06  1.046e-05  1.462e-05   0.00%
Gravity::set_mass_offset()                      10   7.42e-06  1.081e-05  1.304e-05   0.00%
Amr::InitializeInit()                            1  2.186e-06  3.878e-06  1.246e-05   0.00%
Castro::computeNewDt()                           9  8.619e-06  9.836e-06  1.236e-05   0.00%
Castro::FluxRegCrseInit                         10  5.896e-06  8.948e-06  1.179e-05   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.829e-06  5.593e-06  1.045e-05   0.00%
Amr::initialInit()                               1  1.048e-06  2.147e-06  7.471e-06   0.00%
Castro::retry_advance_ctu()                     10  3.733e-06  5.636e-06  7.392e-06   0.00%
Castro::computeInitialDt()                       2  3.115e-06   3.97e-06   4.39e-06   0.00%
Castro::post_regrid()                            1    2.1e-06  3.162e-06  4.191e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.967e-06   2.32e-06  2.688e-06   0.00%
Amr::init()                                      1  1.581e-06  2.047e-06  2.542e-06   0.00%
DistributionMapping::Distribute()                1  1.044e-06  1.318e-06  1.731e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.588      3.589      3.589 100.00%
Amr::coarseTimeStep()                           10       3.37       3.37       3.37  93.89%
Amr::timeStep()                                 10      3.359       3.36      3.361  93.64%
Castro::advance()                               10      3.343      3.344      3.344  93.17%
Castro::subcycle_advance_ctu()                  10      3.271      3.273      3.282  91.43%
Castro::do_advance_ctu()                        10      3.271      3.273      3.282  91.43%
Castro::construct_ctu_hydro_source()            10      2.096      2.334      2.435  67.85%
Gravity::make_radial_gravity()                  21     0.3513     0.3622     0.3875  10.80%
Gravity::get_new_grav_vector()                  11     0.2344     0.2375     0.2394   6.67%
Castro::construct_old_gravity()                 10      0.204     0.2139     0.2349   6.54%
Gravity::get_old_grav_vector()                  10     0.2032     0.2131     0.2343   6.53%
FillPatchIterator::Initialize                   41     0.1802     0.1901     0.2099   5.85%
Castro::construct_new_gravity()                 10     0.2032     0.2048      0.206   5.74%
Amr::init()                                      1     0.2035     0.2037     0.2038   5.68%
Amr::initialInit()                               1     0.1895     0.1898     0.1901   5.30%
FillPatchSingleLevel                            41     0.1302     0.1461     0.1834   5.11%
Castro::initialize_do_advance()                 10     0.1624     0.1729       0.18   5.01%
Amr::InitializeInit()                            1    0.05521     0.1114     0.1537   4.28%
Amr::defBaseLevel()                              1    0.05521     0.1114     0.1537   4.28%
FabArray::ParallelCopy()                        41    0.08821     0.1039     0.1462   4.07%
Castro::initData()                               1    0.04156     0.0959     0.1385   3.86%
Amr::FinalizeInit()                              1     0.0364    0.07836     0.1348   3.76%
Castro::expand_state()                          10     0.1184      0.126     0.1305   3.64%
Castro::estTimeStep()                           21    0.02888    0.07235     0.1266   3.53%
FabArray::ParallelCopy_finish()                 41    0.04096    0.06055     0.1202   3.35%
Castro::clean_state()                           62     0.1133     0.1169     0.1198   3.34%
Castro::computeInitialDt()                       2   0.001745    0.04393     0.1002   2.79%
Castro::initialize_advance()                    10    0.06144    0.07024      0.073   2.03%
Castro::computeTemp()                           63    0.06672    0.06944    0.07227   2.01%
FabArray::setVal()                             143    0.05826    0.06839    0.07142   1.99%
Castro::do_new_sources()                        10    0.05325    0.06367    0.06634   1.85%
Castro::reset_internal_energy(MultiFab)         63    0.04865     0.0504    0.05251   1.46%
Castro::reset_internal_energy(Fab)            1008    0.04834     0.0501    0.05223   1.46%
FabArray::ParallelCopy_nowait()                 41    0.02597    0.04325    0.04939   1.38%
Castro::do_old_sources()                        10    0.03318    0.04528    0.04932   1.37%
StateDataPhysBCFunct::()                        41    0.03707    0.04209     0.0467   1.30%
StateData::FillBoundary(geom)                   41    0.03694    0.04195    0.04655   1.30%
Castro::normalize_species()                     62    0.04128    0.04229     0.0429   1.20%
Castro::post_init()                              1    0.03235    0.03341    0.03392   0.94%
Castro::construct_new_source()                  50    0.02525    0.03001    0.03157   0.88%
Gravity::interpolate_monopole_grav()            21    0.02687    0.02799    0.02895   0.81%
Castro::construct_new_gravity_source()          10    0.02035    0.02348    0.02491   0.69%
Amr::writePlotFile()                             2    0.02343    0.02365    0.02391   0.67%
Castro::post_timestep()                         10    0.01505    0.01604    0.01671   0.47%
Castro::apply_source_to_state()                 20   0.008695    0.01459    0.01636   0.46%
MultiFab::Saxpy()                               20   0.008655    0.01454    0.01631   0.45%
FabArray::setDomainBndry()                      41    0.00611    0.01194    0.01499   0.42%
VisMF::Write(FabArray)                           2    0.01107    0.01192    0.01444   0.40%
Castro::Castro()                                 1   0.008707   0.009962    0.01198   0.33%
Castro::check_for_nan()                         20   0.009392    0.01105    0.01194   0.33%
MultiFab::contains_nan()                        20   0.009366    0.01103    0.01192   0.33%
Castro::construct_old_source()                  50   0.005016    0.01018    0.01187   0.33%
Castro::construct_old_gravity_source()          10   0.004977    0.01011    0.01178   0.33%
Castro::initMFs()                                1   0.005452   0.006456   0.008287   0.23%
Castro::computeNewDt()                           9   0.007312   0.007326   0.007334   0.20%
AmrLevel::AmrLevel(dm)                           1    0.00454   0.005307   0.006766   0.19%
StateData::define()                              4   0.004524    0.00529   0.006744   0.19%
Castro::enforce_min_density()                   62   0.005417   0.006115   0.006603   0.18%
Castro::swap_state_time_levels()                10    0.00282   0.003663     0.0042   0.12%
Castro::post_regrid()                            1   0.001568   0.001945   0.002902   0.08%
Castro::buildMetrics()                           1    0.00142   0.001656     0.0021   0.06%
Castro::finalize_do_advance()                   10   0.000534   0.001077   0.001261   0.04%
Amr::InitAmr()                                   1  0.0002861  0.0003698  0.0005446   0.02%
DistributionMapping::SFCProcessorMapDoIt()       1  1.639e-05  0.0002211  0.0003166   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.597e-06  0.0002141    0.00031   0.01%
Castro::enforce_consistent_e()                   1  7.769e-05  0.0001032  0.0001583   0.00%
FabArrayBase::getCPC()                          41  8.359e-05  8.962e-05  0.0001003   0.00%
Amr::writeSmallPlotFile()                        1  2.434e-05  5.514e-05  7.189e-05   0.00%
Castro::finalize_advance()                      10  5.917e-05  6.253e-05   6.79e-05   0.00%
FabArrayBase::CPC::define()                      2  2.359e-05  2.795e-05  3.561e-05   0.00%
Castro::enforce_speed_limit()                   62  2.359e-05  2.764e-05    3.2e-05   0.00%
Gravity::swapTimeLevels()                       10  5.192e-06  1.179e-05  1.672e-05   0.00%
Amr::initSubcycle()                              1  8.293e-06  1.083e-05  1.649e-05   0.00%
Castro::create_source_corrector()               10  6.281e-06  1.175e-05  1.544e-05   0.00%
Gravity::set_mass_offset()                      10   7.42e-06  1.081e-05  1.304e-05   0.00%
Castro::FluxRegCrseInit                         10  5.896e-06  8.948e-06  1.179e-05   0.00%
Castro::retry_advance_ctu()                     10  3.733e-06  5.636e-06  7.392e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.967e-06   2.32e-06  2.688e-06   0.00%
DistributionMapping::Distribute()                1  1.044e-06  1.318e-06  1.731e-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-8-g8a1c76c0af89) finalized