MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.02-2-gf79f40d4d0cb) initialized

Starting run at 06:31:12 UTC on 2022-02-04.
Successfully read inputs file ... 

Castro git describe: 22.02-1-g342411b81
AMReX git describe: 22.02-2-gf79f40d4d
Microphysics git describe: 22.02

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

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

[STEP 1] Coarse TimeStep time: 0.340662855
[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.327319235
[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.315240437
[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.31328152
[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.31618692
[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.343747518
[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.298242411
[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.291376894
[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.289534432
[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.289624232
[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.009602982  seconds


Ending run at 06:31:15 UTC on 2022-02-04.
Run time = 3.328145489
Run time without initialization = 3.138843807

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



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

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1130     2.1879     2.2305  67.02%
Gravity::make_radial_gravity()                  21     0.3359     0.3465      0.352  10.58%
MultiFab::min()                                 10    0.01254     0.0594     0.1366   4.10%
Castro::initData()                               1    0.04947    0.08067     0.1224   3.68%
Castro::estTimeStep()                           21    0.01861    0.06781     0.0966   2.90%
FabArray::setVal()                             143    0.07065    0.07318    0.07781   2.34%
FabArray::ParallelCopy_finish()                 41    0.03398    0.03908    0.05327   1.60%
Castro::reset_internal_energy()                 63    0.04943    0.05033    0.05158   1.55%
StateData::FillBoundary(geom)                   41    0.03844    0.04204    0.04546   1.37%
FabArray::ParallelCopy_nowait()                 41    0.03939    0.04213    0.04369   1.31%
FillPatchIterator::Initialize                   41    0.02431    0.03139    0.03507   1.05%
Castro::normalize_species()                     62    0.03282    0.03334    0.03456   1.04%
Gravity::interpolate_monopole_grav()            21    0.02764     0.0279     0.0281   0.84%
Castro::construct_new_gravity_source()          10    0.02286    0.02465    0.02668   0.80%
Castro::do_advance_ctu()                        10    0.01866    0.02135     0.0242   0.73%
Castro::expand_state()                          10     0.0166    0.02026    0.02239   0.67%
Castro::computeTemp()                           63    0.01833    0.01892    0.01953   0.59%
Castro::initialize_do_advance()                 10    0.01578    0.01713    0.01829   0.55%
MultiFab::Saxpy()                               20    0.01564    0.01602    0.01665   0.50%
VisMF::Write(FabArray)                           2    0.01184    0.01236     0.0147   0.44%
Castro::initialize_advance()                    10    0.01077    0.01254    0.01456   0.44%
Gravity::get_new_grav_vector()                  11    0.01071    0.01154    0.01305   0.39%
Castro::construct_old_gravity_source()          10    0.01088    0.01171    0.01237   0.37%
MultiFab::contains_nan()                        20    0.01059    0.01138    0.01232   0.37%
FabArray::setDomainBndry()                      41   0.008146    0.01061    0.01175   0.35%
Amr::writePlotFile()                             2   0.008484    0.01066    0.01129   0.34%
Amr::defBaseLevel()                              1   0.006479   0.007836    0.01107   0.33%
Gravity::get_old_grav_vector()                  10   0.008406   0.009457    0.01026   0.31%
StateData::define()                              4   0.004278   0.005048   0.007046   0.21%
Castro::construct_new_source()                  50   0.006525   0.006704   0.006909   0.21%
Castro::enforce_min_density()                   62   0.006073   0.006332   0.006552   0.20%
Castro::swap_state_time_levels()                10    0.00329   0.003887   0.004301   0.13%
main()                                           1   0.003029   0.003621   0.004158   0.12%
Castro::finalize_do_advance()                   10   0.000826    0.00111    0.00126   0.04%
Amr::coarseTimeStep()                           10  0.0004265  0.0006931   0.001255   0.04%
Amr::InitAmr()                                   1  0.0002645   0.000353  0.0005186   0.02%
Amr::timeStep()                                 10  0.0001631  0.0002245  0.0004979   0.01%
DistributionMapping::LeastUsedCPUs()             1  8.285e-06   0.000222  0.0003271   0.01%
Castro::clean_state()                           62  0.0001347  0.0001531  0.0001722   0.01%
StateDataPhysBCFunct::()                        41  0.0001145  0.0001255  0.0001488   0.00%
Castro::construct_old_source()                  50  6.163e-05  8.487e-05  9.972e-05   0.00%
Castro::enforce_consistent_e()                   1  8.073e-05  9.276e-05   9.63e-05   0.00%
FillPatchSingleLevel                            41  6.287e-05  7.067e-05  8.596e-05   0.00%
FabArray::ParallelCopy()                        41  6.629e-05  7.302e-05  8.416e-05   0.00%
Amr::writeSmallPlotFile()                        1  3.157e-05  5.483e-05  7.475e-05   0.00%
Castro::finalize_advance()                      10  5.056e-05   5.88e-05  7.464e-05   0.00%
Amr::FinalizeInit()                              1  3.622e-06   1.32e-05  7.394e-05   0.00%
Castro::construct_old_gravity()                 10  5.602e-05  6.192e-05  7.388e-05   0.00%
Castro::do_old_sources()                        10  5.917e-05  6.695e-05  7.187e-05   0.00%
Castro::do_new_sources()                        10  6.058e-05  6.634e-05  7.147e-05   0.00%
FabArrayBase::getCPC()                          41  4.312e-05  5.383e-05   6.75e-05   0.00%
Castro::construct_new_gravity()                 10  4.157e-05  5.028e-05   6.14e-05   0.00%
Castro::subcycle_advance_ctu()                  10  4.326e-05  5.134e-05  5.878e-05   0.00%
Castro::apply_source_to_state()                 20  3.847e-05  4.721e-05  5.404e-05   0.00%
Castro::enforce_speed_limit()                   62  3.031e-05  3.508e-05   4.51e-05   0.00%
Castro::advance()                               10  3.767e-05  3.973e-05  4.163e-05   0.00%
FabArrayBase::CPC::define()                      2  2.131e-05  2.672e-05  3.385e-05   0.00%
Castro::check_for_nan()                         20  2.634e-05  2.753e-05  2.852e-05   0.00%
Castro::create_source_corrector()               10  1.583e-05  1.826e-05  2.451e-05   0.00%
Amr::initSubcycle()                              1  8.158e-06  1.093e-05  1.793e-05   0.00%
Gravity::swapTimeLevels()                       10  1.323e-05  1.503e-05  1.604e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  7.337e-06  9.082e-06  1.451e-05   0.00%
Castro::post_timestep()                         10  8.269e-06  9.416e-06  1.034e-05   0.00%
Gravity::set_mass_offset()                      10  7.626e-06  8.257e-06  9.636e-06   0.00%
Castro::post_init()                              1  3.059e-06  5.012e-06  9.567e-06   0.00%
Castro::computeNewDt()                           9  7.873e-06  8.716e-06  9.435e-06   0.00%
Amr::InitializeInit()                            1  1.915e-06  2.969e-06  7.133e-06   0.00%
Castro::retry_advance_ctu()                     10  5.151e-06  5.788e-06  6.805e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.704e-06  6.212e-06  6.754e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.454e-06  4.485e-06  5.615e-06   0.00%
Castro::computeInitialDt()                       2  2.768e-06  3.627e-06  4.911e-06   0.00%
Castro::post_regrid()                            1  1.839e-06  2.703e-06  4.793e-06   0.00%
Amr::init()                                      1  1.978e-06  2.295e-06  2.654e-06   0.00%
Amr::initialInit()                               1  1.216e-06  1.501e-06    1.8e-06   0.00%
DistributionMapping::Distribute()                1   8.95e-07  1.203e-06  1.661e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.327      3.328      3.328 100.00%
Amr::coarseTimeStep()                           10      3.125      3.125      3.125  93.90%
Amr::timeStep()                                 10      3.116      3.116      3.116  93.63%
Castro::advance()                               10      3.101      3.101      3.102  93.19%
Castro::subcycle_advance_ctu()                  10      3.024      3.026      3.027  90.96%
Castro::do_advance_ctu()                        10      3.024      3.026      3.027  90.95%
Castro::construct_ctu_hydro_source()            10      2.113      2.188      2.231  67.02%
Gravity::make_radial_gravity()                  21     0.3359     0.3465      0.352  10.58%
Gravity::get_new_grav_vector()                  11     0.2251     0.2264     0.2273   6.83%
Castro::construct_old_gravity()                 10     0.1989     0.2071     0.2124   6.38%
Gravity::get_old_grav_vector()                  10      0.198     0.2063     0.2117   6.36%
Castro::construct_new_gravity()                 10     0.1992     0.2001     0.2008   6.03%
Amr::init()                                      1     0.1886     0.1888     0.1889   5.68%
Amr::initialInit()                               1     0.1752     0.1755     0.1757   5.28%
FillPatchIterator::Initialize                   41     0.1635     0.1656     0.1702   5.11%
Castro::initialize_do_advance()                 10     0.1507     0.1559     0.1608   4.83%
Amr::InitializeInit()                            1    0.06809    0.09978     0.1458   4.38%
Amr::defBaseLevel()                              1    0.06808    0.09977     0.1458   4.38%
MultiFab::min()                                 10    0.01254     0.0594     0.1366   4.10%
FillPatchSingleLevel                            41      0.119     0.1236     0.1315   3.95%
Castro::initData()                               1    0.05337    0.08456     0.1259   3.78%
Castro::expand_state()                          10     0.1051     0.1098     0.1142   3.43%
Castro::clean_state()                           62     0.1062     0.1081     0.1096   3.29%
Amr::FinalizeInit()                              1    0.02958    0.07569     0.1071   3.22%
Castro::estTimeStep()                           21    0.01861    0.06781     0.0966   2.90%
FabArray::ParallelCopy()                        41    0.07725    0.08136    0.09284   2.79%
Castro::computeInitialDt()                       2   0.001709    0.04795    0.07957   2.39%
FabArray::setVal()                             143    0.07065    0.07318    0.07781   2.34%
Castro::initialize_advance()                    10    0.07422    0.07518    0.07772   2.34%
Castro::computeTemp()                           63    0.06779    0.06926    0.07033   2.11%
Castro::do_new_sources()                        10    0.06067    0.06417    0.06778   2.04%
FabArray::ParallelCopy_finish()                 41    0.03398    0.03908    0.05327   1.60%
Castro::reset_internal_energy()                 63    0.04943    0.05033    0.05158   1.55%
Castro::do_old_sources()                        10    0.04681    0.04773     0.0495   1.49%
StateDataPhysBCFunct::()                        41    0.03856    0.04217    0.04558   1.37%
StateData::FillBoundary(geom)                   41    0.03844    0.04204    0.04546   1.37%
FabArray::ParallelCopy_nowait()                 41    0.03949    0.04221    0.04376   1.31%
Castro::normalize_species()                     62    0.03282    0.03334    0.03456   1.04%
Castro::construct_new_source()                  50    0.02943    0.03135    0.03343   1.00%
Gravity::interpolate_monopole_grav()            21    0.02764     0.0279     0.0281   0.84%
Castro::post_init()                              1    0.02698    0.02725     0.0275   0.83%
Castro::construct_new_gravity_source()          10    0.02286    0.02465    0.02668   0.80%
Amr::writePlotFile()                             2    0.02275    0.02301    0.02334   0.70%
Castro::apply_source_to_state()                 20    0.01568    0.01607     0.0167   0.50%
MultiFab::Saxpy()                               20    0.01564    0.01602    0.01665   0.50%
VisMF::Write(FabArray)                           2    0.01184    0.01236     0.0147   0.44%
Castro::post_timestep()                         10    0.01427    0.01437    0.01454   0.44%
Castro::construct_old_source()                  50    0.01095    0.01179    0.01246   0.37%
Castro::construct_old_gravity_source()          10    0.01088    0.01171    0.01237   0.37%
Castro::check_for_nan()                         20    0.01062    0.01141    0.01235   0.37%
MultiFab::contains_nan()                        20    0.01059    0.01138    0.01232   0.37%
FabArray::setDomainBndry()                      41   0.008146    0.01061    0.01175   0.35%
Castro::computeNewDt()                           9   0.006765   0.007319     0.0077   0.23%
AmrLevel::AmrLevel(dm)                           1   0.004286   0.005057   0.007057   0.21%
StateData::define()                              4   0.004278   0.005048   0.007046   0.21%
Castro::enforce_min_density()                   62   0.006073   0.006332   0.006552   0.20%
Castro::swap_state_time_levels()                10    0.00329   0.003887   0.004301   0.13%
Castro::post_regrid()                            1   0.001383   0.001495   0.001805   0.05%
Castro::finalize_do_advance()                   10   0.000826    0.00111    0.00126   0.04%
Amr::InitAmr()                                   1  0.0002726  0.0003639  0.0005359   0.02%
DistributionMapping::SFCProcessorMapDoIt()       1  1.427e-05  0.0002277   0.000333   0.01%
DistributionMapping::LeastUsedCPUs()             1  8.285e-06   0.000222  0.0003271   0.01%
FabArrayBase::getCPC()                          41  6.832e-05  8.055e-05  0.0001006   0.00%
Castro::enforce_consistent_e()                   1  8.073e-05  9.276e-05   9.63e-05   0.00%
Castro::finalize_advance()                      10  5.648e-05  6.501e-05  8.057e-05   0.00%
Amr::writeSmallPlotFile()                        1  3.157e-05  5.483e-05  7.475e-05   0.00%
Castro::enforce_speed_limit()                   62  3.031e-05  3.508e-05   4.51e-05   0.00%
FabArrayBase::CPC::define()                      2  2.131e-05  2.672e-05  3.385e-05   0.00%
Castro::create_source_corrector()               10  1.583e-05  1.826e-05  2.451e-05   0.00%
Amr::initSubcycle()                              1  8.158e-06  1.093e-05  1.793e-05   0.00%
Gravity::swapTimeLevels()                       10  1.323e-05  1.503e-05  1.604e-05   0.00%
Gravity::set_mass_offset()                      10  7.626e-06  8.257e-06  9.636e-06   0.00%
Castro::retry_advance_ctu()                     10  5.151e-06  5.788e-06  6.805e-06   0.00%
Castro::FluxRegFineAdd()                        10  5.704e-06  6.212e-06  6.754e-06   0.00%
DistributionMapping::Distribute()                1   8.95e-07  1.203e-06  1.661e-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.02-2-gf79f40d4d0cb) finalized