MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (23.07-32-g587542a395e4) initialized

Starting run at 17:18:56 UTC on 2023-07-21.
Successfully read inputs file ... 

Castro git describe: 23.07-17-g6ddff09fd
AMReX git describe: 23.07-32-g587542a39
Microphysics git describe: 23.07-37-g5a7415fe

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

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

[STEP 1] Coarse TimeStep time: 0.361153877
[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 at time 4.541742215e-05 with dt = 4.768829326e-05
[Level 0 step 2] Advanced 262144 cells

[STEP 2] Coarse TimeStep time: 0.345933025
[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 at time 9.31057154e-05 with dt = 5.007270792e-05
[Level 0 step 3] Advanced 262144 cells

[STEP 3] Coarse TimeStep time: 0.33121537
[STEP 3] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 3 TIME = 0.0001431784233 DT = 5.007270792e-05

[Level 0 step 4] ADVANCE at time 0.0001431784233 with dt = 5.257634331e-05
[Level 0 step 4] Advanced 262144 cells

[STEP 4] Coarse TimeStep time: 0.331589843
[STEP 4] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 4 TIME = 0.0001957547666 DT = 5.257634331e-05

[Level 0 step 5] ADVANCE at time 0.0001957547666 with dt = 5.520516048e-05
[Level 0 step 5] Advanced 262144 cells

[STEP 5] Coarse TimeStep time: 0.341880721
[STEP 5] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 5 TIME = 0.0002509599271 DT = 5.520516048e-05

[Level 0 step 6] ADVANCE at time 0.0002509599271 with dt = 5.79654185e-05
[Level 0 step 6] Advanced 262144 cells

[STEP 6] Coarse TimeStep time: 0.373647139
[STEP 6] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 6 TIME = 0.0003089253456 DT = 5.79654185e-05

[Level 0 step 7] ADVANCE at time 0.0003089253456 with dt = 6.086368943e-05
[Level 0 step 7] Advanced 262144 cells

[STEP 7] Coarse TimeStep time: 0.315140302
[STEP 7] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 7 TIME = 0.000369789035 DT = 6.086368943e-05

[Level 0 step 8] ADVANCE at time 0.000369789035 with dt = 6.39068739e-05
[Level 0 step 8] Advanced 262144 cells

[STEP 8] Coarse TimeStep time: 0.315332442
[STEP 8] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 8 TIME = 0.000433695909 DT = 6.39068739e-05

[Level 0 step 9] ADVANCE at time 0.000433695909 with dt = 6.71022176e-05
[Level 0 step 9] Advanced 262144 cells

[STEP 9] Coarse TimeStep time: 0.317042957
[STEP 9] FAB kilobyte spread across MPI nodes: [57998 ... 57998]

STEP = 9 TIME = 0.0005007981265 DT = 6.71022176e-05

[Level 0 step 10] ADVANCE at time 0.0005007981265 with dt = 7.045732848e-05
[Level 0 step 10] Advanced 262144 cells

[STEP 10] Coarse TimeStep time: 0.315337151
[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.010584247  seconds


Ending run at 17:19:00 UTC on 2023-07-21.
Run time = 3.545750759
Run time without initialization = 3.366564175

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



TinyProfiler total time across processes [min...avg...max]: 3.544 ... 3.545 ... 3.546

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.2641     2.3619     2.4010  67.71%
Gravity::make_radial_gravity()                  21     0.3105     0.3177     0.3357   9.47%
check_for_negative_density()                    10    0.02761     0.0693     0.1698   4.79%
Castro::initData()                               1    0.03844    0.07751     0.1137   3.21%
Castro::estTimeStep()                           21    0.03081    0.07065      0.113   3.19%
FabArray::ParallelCopy_finish()                 41    0.03443    0.06862     0.1076   3.04%
FabArray::setVal()                             143    0.06278    0.07372    0.09029   2.55%
amrex::Copy()                                  193    0.06494    0.07791    0.08732   2.46%
Castro::reset_internal_energy(Fab)            1008    0.04793    0.04977    0.05126   1.45%
FabArray::ParallelCopy_nowait()                 41    0.03505    0.04099    0.04317   1.22%
Castro::normalize_species()                     62    0.04085    0.04218    0.04306   1.21%
FillPatchIterator::Initialize                   41    0.02801     0.0339    0.04096   1.16%
StateData::FillBoundary(geom)                   41    0.03349    0.03717    0.04051   1.14%
Gravity::interpolate_monopole_grav()            21    0.02788    0.02823    0.02859   0.81%
Castro::construct_new_gravity_source()          10     0.0214    0.02358    0.02529   0.71%
Castro::initialize_do_advance()                 10    0.01184    0.01923    0.02494   0.70%
Castro::computeTemp()                           63     0.0193    0.01968    0.02007   0.57%
VisMF::Write(FabArray)                           2    0.01402    0.01452    0.01761   0.50%
Castro::initialize_advance()                    10   0.004414    0.01038    0.01452   0.41%
FabArray::Saxpy()                               20    0.01072    0.01287    0.01379   0.39%
FabArray::setDomainBndry()                      41   0.008434    0.01112    0.01232   0.35%
MultiFab::contains_nan()                        20   0.009406    0.01039    0.01125   0.32%
Castro::construct_old_gravity_source()          10   0.006597   0.009395    0.01003   0.28%
Amr::writePlotFile()                             2   0.006505   0.008825   0.009277   0.26%
Castro::initMFs()                                1   0.007046   0.007518   0.008855   0.25%
Castro::construct_new_source()                  50   0.005703     0.0065   0.006921   0.20%
main()                                           1   0.005316   0.006089   0.006803   0.19%
StateData::define()                              4   0.005814    0.00606   0.006719   0.19%
Amr::coarseTimeStep()                           10   0.001424   0.005309   0.006514   0.18%
Castro::enforce_min_density()                   62   0.005857   0.006134   0.006439   0.18%
Castro::swap_state_time_levels()                10   0.002224   0.004544   0.004926   0.14%
Gravity::get_new_grav_vector()                  11   0.003316    0.00386   0.004306   0.12%
Gravity::get_old_grav_vector()                  10   0.001559   0.001952   0.002243   0.06%
Castro::finalize_do_advance()                   10   0.000624   0.001684   0.002048   0.06%
Castro::buildMetrics()                           1   0.001684    0.00176   0.001954   0.06%
AmrLevel::FillPatch()                           41  0.0009363   0.001118   0.001301   0.04%
Amr::InitAmr()                                   1  0.0002694  0.0003119  0.0005562   0.02%
Amr::timeStep()                                 10  0.0002272  0.0002835  0.0004122   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002967   0.000315  0.0003563   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.196e-06  0.0002751  0.0003213   0.01%
Castro::computeNewDt()                           9   1.23e-05  8.059e-05  0.0002575   0.01%
Castro::do_advance_ctu()                        10  0.0001673  0.0002028  0.0002388   0.01%
Castro::finalize_advance()                      10  8.925e-05  0.0001119  0.0002034   0.01%
Castro::clean_state()                           62  0.0001446  0.0001565  0.0001837   0.01%
FillPatchIterator::FillFromLevel0()             41  0.0001048  0.0001227  0.0001521   0.00%
Castro::enforce_consistent_e()                   1  7.932e-05  9.078e-05  0.0001177   0.00%
StateDataPhysBCFunct::()                        41  8.793e-05  0.0001005   0.000114   0.00%
Amr::FinalizeInit()                              1  3.244e-06  1.864e-05  0.0001087   0.00%
Castro::do_old_sources()                        10   8.83e-05  9.463e-05  0.0001037   0.00%
FabArray::ParallelCopy()                        41  6.228e-05    7.8e-05  8.927e-05   0.00%
Castro::Castro()                                 1  5.945e-05  6.906e-05  8.711e-05   0.00%
Castro::construct_old_source()                  50  5.983e-05   7.29e-05  8.107e-05   0.00%
FabArrayBase::getCPC()                          41  4.273e-05  5.463e-05  7.769e-05   0.00%
FillPatchSingleLevel                            41  6.511e-05  7.012e-05  7.379e-05   0.00%
Castro::advance()                               10  4.561e-05  5.874e-05   7.15e-05   0.00%
Castro::construct_old_gravity()                 10  4.401e-05  5.372e-05  7.102e-05   0.00%
Castro::do_new_sources()                        10  5.865e-05  6.383e-05   6.95e-05   0.00%
Castro::subcycle_advance_ctu()                  10  5.227e-05  5.885e-05  6.452e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.829e-05  5.238e-05   6.45e-05   0.00%
Castro::construct_new_gravity()                 10  3.785e-05  4.363e-05  5.767e-05   0.00%
Castro::apply_source_to_state()                 20  4.112e-05  4.721e-05  5.637e-05   0.00%
Amr::defBaseLevel()                              1   2.42e-05  3.227e-05  4.814e-05   0.00%
FabArrayBase::CPC::define()                      2  2.004e-05  2.488e-05  3.623e-05   0.00%
Castro::enforce_speed_limit()                   62  2.776e-05   3.01e-05  3.276e-05   0.00%
Castro::expand_state()                          10  2.095e-05  2.609e-05  2.994e-05   0.00%
Castro::post_timestep()                         10  1.386e-05   1.79e-05  2.881e-05   0.00%
Castro::check_for_nan()                         20  2.037e-05  2.325e-05  2.564e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.607e-05  1.856e-05  2.179e-05   0.00%
Castro::retry_advance_ctu()                     10  7.578e-06  9.838e-06  2.061e-05   0.00%
Castro::FluxRegCrseInit                         10  7.278e-06  9.661e-06  2.052e-05   0.00%
Gravity::set_mass_offset()                      10  1.015e-05  1.434e-05  1.835e-05   0.00%
Gravity::swapTimeLevels()                       10  1.263e-05  1.417e-05  1.582e-05   0.00%
Amr::initSubcycle()                              1   8.53e-06  9.341e-06  1.117e-05   0.00%
Castro::create_source_corrector()               10  8.244e-06  8.808e-06  1.015e-05   0.00%
Amr::initialInit()                               1  1.282e-06  2.256e-06   7.43e-06   0.00%
Amr::InitializeInit()                            1  1.837e-06  2.543e-06  6.183e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.747e-06  4.257e-06  5.745e-06   0.00%
Castro::computeInitialDt()                       2  3.564e-06  4.638e-06  5.527e-06   0.00%
Castro::post_regrid()                            1  1.769e-06   3.52e-06  5.397e-06   0.00%
Castro::post_init()                              1  3.374e-06   4.25e-06  5.132e-06   0.00%
Amr::init()                                      1   1.76e-06  2.354e-06  2.874e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.197e-06    2.4e-06    2.7e-06   0.00%
DistributionMapping::Distribute()                1   9.15e-07  1.028e-06  1.148e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.544      3.545      3.546 100.00%
Amr::coarseTimeStep()                           10      3.349      3.349      3.349  94.46%
Amr::timeStep()                                 10      3.327      3.329      3.333  93.99%
Castro::advance()                               10      3.311      3.312      3.315  93.48%
Castro::subcycle_advance_ctu()                  10       3.22      3.234      3.243  91.46%
Castro::do_advance_ctu()                        10       3.22      3.234      3.243  91.46%
Castro::construct_ctu_hydro_source()            10      2.447      2.452      2.454  69.22%
Gravity::make_radial_gravity()                  21     0.3263     0.3336     0.3488   9.84%
AmrLevel::FillPatch()                           41     0.1888     0.2225     0.2452   6.91%
Gravity::get_new_grav_vector()                  11     0.2181     0.2197     0.2208   6.23%
FillPatchIterator::Initialize                   41     0.1604     0.1922     0.2133   6.02%
Castro::construct_old_gravity()                 10     0.1926     0.1987      0.213   6.01%
Gravity::get_old_grav_vector()                  10     0.1921     0.1979     0.2124   5.99%
Castro::initialize_do_advance()                 10     0.1731     0.1894     0.2022   5.70%
Castro::construct_new_gravity()                 10     0.1943     0.1954     0.1961   5.53%
Amr::init()                                      1     0.1785     0.1787     0.1788   5.04%
FillPatchIterator::FillFromLevel0()             41      0.118     0.1472     0.1769   4.99%
FillPatchSingleLevel                            41     0.1178     0.1471     0.1767   4.98%
check_for_negative_density()                    10    0.02761     0.0693     0.1698   4.79%
Amr::initialInit()                               1      0.163     0.1634     0.1636   4.61%
Castro::expand_state()                          10     0.1131      0.133     0.1481   4.18%
FabArray::ParallelCopy()                        41    0.07715     0.1098     0.1429   4.03%
Amr::InitializeInit()                            1    0.06024    0.09954     0.1356   3.82%
Amr::defBaseLevel()                              1    0.06023    0.09954     0.1356   3.82%
Castro::clean_state()                           62     0.1139     0.1173     0.1194   3.37%
Castro::initData()                               1     0.0426    0.08148     0.1179   3.32%
Castro::estTimeStep()                           21    0.03081    0.07065      0.113   3.19%
FabArray::ParallelCopy_finish()                 41    0.03443    0.06862     0.1076   3.04%
Amr::FinalizeInit()                              1    0.02769    0.06388     0.1034   2.92%
Castro::do_old_sources()                        10    0.09218    0.09763     0.1025   2.89%
FabArray::setVal()                             143    0.06278    0.07372    0.09029   2.55%
Castro::initialize_advance()                    10    0.06464    0.07495    0.08874   2.50%
amrex::Copy()                                  193    0.06494    0.07791    0.08732   2.46%
Castro::computeInitialDt()                       2   0.001999    0.03803    0.07738   2.18%
Castro::computeTemp()                           63    0.06786    0.06976    0.07166   2.02%
Castro::do_new_sources()                        10    0.06053    0.06404     0.0689   1.94%
Castro::reset_internal_energy(MultiFab)         63    0.04829    0.05008    0.05159   1.45%
Castro::reset_internal_energy(Fab)            1008    0.04793    0.04977    0.05126   1.45%
FabArray::ParallelCopy_nowait()                 41    0.03516    0.04107    0.04323   1.22%
Castro::normalize_species()                     62    0.04085    0.04218    0.04306   1.21%
StateDataPhysBCFunct::()                        41     0.0336    0.03727    0.04061   1.15%
StateData::FillBoundary(geom)                   41    0.03349    0.03717    0.04051   1.14%
Castro::construct_new_source()                  50     0.0271    0.03008    0.03201   0.90%
Gravity::interpolate_monopole_grav()            21    0.02788    0.02823    0.02859   0.81%
Amr::writePlotFile()                             2    0.02586    0.02602    0.02618   0.74%
Castro::post_init()                              1    0.02499    0.02526    0.02539   0.72%
Castro::construct_new_gravity_source()          10     0.0214    0.02358    0.02529   0.71%
Castro::finalize_do_advance()                   10    0.01582    0.02051    0.02345   0.66%
Castro::post_timestep()                         10    0.01579    0.01628    0.01797   0.51%
VisMF::Write(FabArray)                           2    0.01402    0.01452    0.01761   0.50%
Castro::computeNewDt()                           9     0.0128    0.01388     0.0142   0.40%
Castro::apply_source_to_state()                 20    0.01076    0.01292    0.01384   0.39%
FabArray::Saxpy()                               20    0.01072    0.01287    0.01379   0.39%
Castro::Castro()                                 1    0.01135    0.01166    0.01244   0.35%
FabArray::setDomainBndry()                      41   0.008434    0.01112    0.01232   0.35%
Castro::check_for_nan()                         20   0.009426    0.01041    0.01128   0.32%
MultiFab::contains_nan()                        20   0.009406    0.01039    0.01125   0.32%
Castro::construct_old_source()                  50   0.006657   0.009468    0.01011   0.29%
Castro::construct_old_gravity_source()          10   0.006597   0.009395    0.01003   0.28%
Castro::initMFs()                                1   0.007046   0.007518   0.008855   0.25%
AmrLevel::AmrLevel(dm)                           1    0.00583   0.006078    0.00674   0.19%
StateData::define()                              4   0.005814    0.00606   0.006719   0.19%
Castro::enforce_min_density()                   62   0.005857   0.006134   0.006439   0.18%
Castro::finalize_advance()                      10   0.002981   0.003751   0.005568   0.16%
Castro::swap_state_time_levels()                10   0.002224   0.004544   0.004926   0.14%
Castro::buildMetrics()                           1   0.001813   0.001891   0.002108   0.06%
Castro::post_regrid()                            1   0.001516   0.001615   0.001681   0.05%
Amr::InitAmr()                                   1  0.0002786  0.0003212  0.0005674   0.02%
DistributionMapping::SFCProcessorMapDoIt()       1  1.427e-05  0.0002804  0.0003281   0.01%
DistributionMapping::LeastUsedCPUs()             1  9.196e-06  0.0002751  0.0003213   0.01%
Castro::enforce_consistent_e()                   1  7.932e-05  9.078e-05  0.0001177   0.00%
FabArrayBase::getCPC()                          41  6.661e-05   7.95e-05  0.0001073   0.00%
Amr::writeSmallPlotFile()                        1  2.829e-05  5.238e-05   6.45e-05   0.00%
FabArrayBase::CPC::define()                      2  2.004e-05  2.488e-05  3.623e-05   0.00%
Castro::enforce_speed_limit()                   62  2.776e-05   3.01e-05  3.276e-05   0.00%
Castro::retry_advance_ctu()                     10  7.578e-06  9.838e-06  2.061e-05   0.00%
Castro::FluxRegCrseInit                         10  7.278e-06  9.661e-06  2.052e-05   0.00%
Gravity::set_mass_offset()                      10  1.015e-05  1.434e-05  1.835e-05   0.00%
Gravity::swapTimeLevels()                       10  1.263e-05  1.417e-05  1.582e-05   0.00%
Amr::initSubcycle()                              1   8.53e-06  9.341e-06  1.117e-05   0.00%
Castro::create_source_corrector()               10  8.244e-06  8.808e-06  1.015e-05   0.00%
Castro::FluxRegFineAdd()                        10  2.197e-06    2.4e-06    2.7e-06   0.00%
DistributionMapping::Distribute()                1   9.15e-07  1.028e-06  1.148e-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]

Pinned Memory Usage:
---------------------------------------------------------------------------------------------------------------------
Name                            Nalloc  Nfree  AvgMem min  AvgMem avg  AvgMem max  MaxMem min  MaxMem avg  MaxMem max
---------------------------------------------------------------------------------------------------------------------
The_Pinned_Arena::Initialize()       8      8      29   B      34   B      46   B    8192 KiB    8192 KiB    8192 KiB
---------------------------------------------------------------------------------------------------------------------

AMReX (23.07-32-g587542a395e4) finalized