MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.02-15-g067830e6c9fe) initialized

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

Castro git describe: 22.02-4-g0d942c92d
AMReX git describe: 22.02-15-g067830e6c
Microphysics git describe: 22.02-10-g1a114f0a

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

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

[STEP 1] Coarse TimeStep time: 0.353290063
[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.340784083
[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.351275322
[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.319747357
[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.320126371
[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.340455262
[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.299459895
[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.293994464
[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.293916716
[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.294635282
[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.009569154  seconds


Ending run at 06:31:41 UTC on 2022-02-28.
Run time = 3.397847903
Run time without initialization = 3.221238051

  Average number of zones advanced per microsecond: 0.814
  Average number of zones advanced per microsecond per rank: 0.102



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

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1932     2.2327     2.2792  67.08%
Gravity::make_radial_gravity()                  21     0.3371     0.3462     0.3556  10.47%
Castro::initData()                               1    0.03945    0.08079     0.1172   3.45%
MultiFab::min()                                 10    0.02058     0.0709     0.1131   3.33%
Castro::estTimeStep()                           21    0.02027    0.05943    0.09962   2.93%
FabArray::setVal()                             143    0.06998     0.0739    0.08095   2.38%
FabArray::ParallelCopy_finish()                 41    0.03518    0.04661    0.05574   1.64%
Castro::reset_internal_energy()                 63    0.04798    0.05088      0.052   1.53%
FabArray::ParallelCopy_nowait()                 41    0.04136    0.04411    0.04679   1.38%
StateData::FillBoundary(geom)                   41    0.04218    0.04359    0.04659   1.37%
FillPatchIterator::Initialize                   41    0.02477    0.03439    0.03733   1.10%
Castro::normalize_species()                     62    0.03324    0.03439    0.03558   1.05%
Gravity::interpolate_monopole_grav()            21    0.02841    0.02874    0.02919   0.86%
Castro::construct_new_gravity_source()          10    0.02283    0.02395    0.02596   0.76%
Castro::do_advance_ctu()                        10    0.01803     0.0219    0.02573   0.76%
Castro::expand_state()                          10    0.01793    0.02164    0.02335   0.69%
Castro::computeTemp()                           63    0.01721    0.01917    0.02048   0.60%
MultiFab::Saxpy()                               20    0.01536    0.01703    0.02044   0.60%
Castro::initialize_do_advance()                 10    0.01655    0.01846    0.01937   0.57%
Castro::construct_old_gravity_source()          10    0.01101    0.01244    0.01633   0.48%
VisMF::Write(FabArray)                           2    0.01129    0.01206    0.01429   0.42%
Castro::initialize_advance()                    10    0.01029    0.01209    0.01397   0.41%
Gravity::get_new_grav_vector()                  11    0.01059    0.01204    0.01357   0.40%
MultiFab::contains_nan()                        20    0.01079    0.01187    0.01274   0.37%
FabArray::setDomainBndry()                      41   0.008354    0.01137    0.01218   0.36%
Gravity::get_old_grav_vector()                  10   0.008722    0.01034    0.01187   0.35%
Amr::writePlotFile()                             2   0.008295     0.0107    0.01162   0.34%
Amr::defBaseLevel()                              1   0.006717   0.007065   0.009313   0.27%
Castro::construct_new_source()                  50   0.006587   0.006764   0.006976   0.21%
Castro::enforce_min_density()                   62   0.006041   0.006357   0.006642   0.20%
StateData::define()                              4   0.004351   0.004483   0.004866   0.14%
main()                                           1   0.003589   0.003876   0.004146   0.12%
Castro::swap_state_time_levels()                10   0.002947   0.003791   0.003933   0.12%
Castro::finalize_do_advance()                   10  0.0008055  0.0009358    0.00103   0.03%
Amr::coarseTimeStep()                           10  0.0003229  0.0005827   0.001025   0.03%
Amr::timeStep()                                 10  0.0001739  0.0002313  0.0004501   0.01%
Amr::InitAmr()                                   1  0.0002698  0.0003014  0.0004369   0.01%
StateDataPhysBCFunct::()                        41  0.0001132  0.0001377  0.0001985   0.01%
Castro::clean_state()                           62  0.0001341  0.0001499  0.0001732   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.583e-06  0.0001443  0.0001673   0.00%
Castro::construct_old_source()                  50  6.522e-05  8.594e-05  0.0001398   0.00%
Castro::enforce_consistent_e()                   1  7.822e-05  9.269e-05  0.0001081   0.00%
Castro::apply_source_to_state()                 20  3.661e-05  5.947e-05  9.126e-05   0.00%
Castro::do_old_sources()                        10  5.132e-05  6.518e-05  8.086e-05   0.00%
FabArray::ParallelCopy()                        41   5.71e-05  6.612e-05  7.647e-05   0.00%
FillPatchSingleLevel                            41  5.531e-05  6.838e-05  7.533e-05   0.00%
Castro::do_new_sources()                        10  5.175e-05  6.234e-05  7.147e-05   0.00%
Amr::FinalizeInit()                              1  4.215e-06  1.371e-05  6.943e-05   0.00%
FabArrayBase::getCPC()                          41  4.792e-05  5.615e-05  6.722e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.618e-05  5.229e-05  6.675e-05   0.00%
Castro::subcycle_advance_ctu()                  10  5.027e-05  5.856e-05  6.533e-05   0.00%
Castro::construct_old_gravity()                 10  4.941e-05   5.52e-05  6.119e-05   0.00%
Castro::advance()                               10  3.489e-05  4.292e-05   5.84e-05   0.00%
Castro::finalize_advance()                      10  5.167e-05  5.482e-05  5.817e-05   0.00%
Castro::construct_new_gravity()                 10  4.145e-05  4.825e-05   5.72e-05   0.00%
Castro::enforce_speed_limit()                   62  3.087e-05  3.373e-05  3.812e-05   0.00%
FabArrayBase::CPC::define()                      2  2.401e-05   2.71e-05    3.3e-05   0.00%
Castro::check_for_nan()                         20  2.427e-05  2.588e-05  3.034e-05   0.00%
Castro::create_source_corrector()               10   1.25e-05  1.646e-05  1.986e-05   0.00%
Gravity::swapTimeLevels()                       10  1.075e-05  1.291e-05  1.704e-05   0.00%
Castro::post_init()                              1  3.471e-06  6.276e-06  1.324e-05   0.00%
Castro::post_timestep()                         10  9.037e-06  1.041e-05  1.194e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  7.004e-06   9.12e-06   1.08e-05   0.00%
Amr::initSubcycle()                              1  8.868e-06  9.411e-06  9.848e-06   0.00%
Gravity::set_mass_offset()                      10  7.581e-06  8.537e-06  9.173e-06   0.00%
Castro::computeNewDt()                           9  6.851e-06   8.09e-06  9.041e-06   0.00%
Castro::FluxRegFineAdd()                        10  6.122e-06  6.905e-06  7.951e-06   0.00%
Amr::InitializeInit()                            1   1.91e-06  3.444e-06  7.763e-06   0.00%
Castro::retry_advance_ctu()                     10  5.895e-06  6.382e-06  7.016e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1   3.92e-06  4.807e-06  6.873e-06   0.00%
Castro::post_regrid()                            1  1.897e-06  3.413e-06  5.461e-06   0.00%
Castro::computeInitialDt()                       2  3.429e-06  4.361e-06  5.005e-06   0.00%
Amr::init()                                      1  1.999e-06  2.409e-06  2.894e-06   0.00%
Amr::initialInit()                               1  1.152e-06  1.481e-06  1.734e-06   0.00%
DistributionMapping::Distribute()                1   8.99e-07  1.109e-06  1.396e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.397      3.398      3.398 100.00%
Amr::coarseTimeStep()                           10      3.208      3.208      3.208  94.40%
Amr::timeStep()                                 10      3.198      3.199      3.199  94.15%
Castro::advance()                               10      3.184      3.184      3.184  93.71%
Castro::subcycle_advance_ctu()                  10      3.108       3.11      3.113  91.62%
Castro::do_advance_ctu()                        10      3.108       3.11      3.113  91.61%
Castro::construct_ctu_hydro_source()            10      2.193      2.233      2.279  67.08%
Gravity::make_radial_gravity()                  21     0.3371     0.3462     0.3556  10.47%
Gravity::get_new_grav_vector()                  11     0.2264     0.2279     0.2289   6.74%
Castro::construct_old_gravity()                 10     0.2015     0.2097     0.2216   6.52%
Gravity::get_old_grav_vector()                  10     0.2006     0.2088     0.2209   6.50%
Castro::construct_new_gravity()                 10      0.203     0.2039     0.2047   6.03%
FillPatchIterator::Initialize                   41      0.172     0.1804       0.19   5.59%
Amr::init()                                      1      0.176     0.1761     0.1762   5.18%
Castro::initialize_do_advance()                 10     0.1577     0.1635     0.1687   4.96%
Amr::initialInit()                               1     0.1628      0.163     0.1632   4.80%
FillPatchSingleLevel                            41     0.1227     0.1347     0.1429   4.21%
Amr::InitializeInit()                            1    0.05764    0.09931     0.1357   3.99%
Amr::defBaseLevel()                              1    0.05764    0.09931     0.1357   3.99%
Castro::initData()                               1    0.04342    0.08482     0.1213   3.57%
Castro::expand_state()                          10     0.1095     0.1155     0.1203   3.54%
MultiFab::min()                                 10    0.02058     0.0709     0.1131   3.33%
Castro::clean_state()                           62     0.1046       0.11     0.1121   3.30%
Amr::FinalizeInit()                              1    0.02737    0.06371     0.1053   3.10%
Castro::estTimeStep()                           21    0.02027    0.05943    0.09962   2.93%
FabArray::ParallelCopy()                        41    0.08025    0.09086    0.09828   2.89%
FabArray::setVal()                             143    0.06998     0.0739    0.08095   2.38%
Castro::computeInitialDt()                       2   0.001715     0.0381     0.0798   2.35%
Castro::initialize_advance()                    10    0.07105    0.07357    0.07571   2.23%
Castro::computeTemp()                           63    0.06518    0.07005    0.07248   2.13%
Castro::do_new_sources()                        10    0.06044    0.06374    0.06723   1.98%
Castro::do_old_sources()                        10    0.04649    0.05077    0.05739   1.69%
FabArray::ParallelCopy_finish()                 41    0.03518    0.04661    0.05574   1.64%
Castro::reset_internal_energy()                 63    0.04798    0.05088      0.052   1.53%
FabArray::ParallelCopy_nowait()                 41    0.04146    0.04419    0.04688   1.38%
StateDataPhysBCFunct::()                        41    0.04232    0.04372    0.04671   1.37%
StateData::FillBoundary(geom)                   41    0.04218    0.04359    0.04659   1.37%
Castro::normalize_species()                     62    0.03324    0.03439    0.03558   1.05%
Castro::construct_new_source()                  50    0.02956    0.03071    0.03294   0.97%
Gravity::interpolate_monopole_grav()            21    0.02841    0.02874    0.02919   0.86%
Castro::construct_new_gravity_source()          10    0.02283    0.02395    0.02596   0.76%
Castro::post_init()                              1    0.02486    0.02501    0.02512   0.74%
Amr::writePlotFile()                             2    0.02259    0.02276    0.02299   0.68%
Castro::apply_source_to_state()                 20     0.0154    0.01709     0.0205   0.60%
MultiFab::Saxpy()                               20    0.01536    0.01703    0.02044   0.60%
Castro::construct_old_source()                  50    0.01108    0.01253    0.01641   0.48%
Castro::construct_old_gravity_source()          10    0.01101    0.01244    0.01633   0.48%
Castro::post_timestep()                         10    0.01385    0.01444    0.01467   0.43%
VisMF::Write(FabArray)                           2    0.01129    0.01206    0.01429   0.42%
Castro::check_for_nan()                         20    0.01082     0.0119    0.01276   0.38%
MultiFab::contains_nan()                        20    0.01079    0.01187    0.01274   0.37%
FabArray::setDomainBndry()                      41   0.008354    0.01137    0.01218   0.36%
Castro::computeNewDt()                           9   0.006836   0.007275   0.007571   0.22%
Castro::enforce_min_density()                   62   0.006041   0.006357   0.006642   0.20%
AmrLevel::AmrLevel(dm)                           1    0.00436   0.004492   0.004874   0.14%
StateData::define()                              4   0.004351   0.004483   0.004866   0.14%
Castro::swap_state_time_levels()                10   0.002947   0.003791   0.003933   0.12%
Castro::post_regrid()                            1   0.001424     0.0015    0.00156   0.05%
Castro::finalize_do_advance()                   10  0.0008055  0.0009358    0.00103   0.03%
Amr::InitAmr()                                   1  0.0002795  0.0003108  0.0004457   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1   1.47e-05  0.0001503  0.0001748   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.583e-06  0.0001443  0.0001673   0.00%
Castro::enforce_consistent_e()                   1  7.822e-05  9.269e-05  0.0001081   0.00%
FabArrayBase::getCPC()                          41  7.448e-05  8.326e-05  0.0001002   0.00%
Amr::writeSmallPlotFile()                        1  2.618e-05  5.229e-05  6.675e-05   0.00%
Castro::finalize_advance()                      10  5.779e-05  6.172e-05  6.505e-05   0.00%
Castro::enforce_speed_limit()                   62  3.087e-05  3.373e-05  3.812e-05   0.00%
FabArrayBase::CPC::define()                      2  2.401e-05   2.71e-05    3.3e-05   0.00%
Castro::create_source_corrector()               10   1.25e-05  1.646e-05  1.986e-05   0.00%
Gravity::swapTimeLevels()                       10  1.075e-05  1.291e-05  1.704e-05   0.00%
Amr::initSubcycle()                              1  8.868e-06  9.411e-06  9.848e-06   0.00%
Gravity::set_mass_offset()                      10  7.581e-06  8.537e-06  9.173e-06   0.00%
Castro::FluxRegFineAdd()                        10  6.122e-06  6.905e-06  7.951e-06   0.00%
Castro::retry_advance_ctu()                     10  5.895e-06  6.382e-06  7.016e-06   0.00%
DistributionMapping::Distribute()                1   8.99e-07  1.109e-06  1.396e-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-15-g067830e6c9fe) finalized