MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (23.02-34-g774f5443a770) initialized

Starting run at 22:41:27 UTC on 2023-02-12.
Successfully read inputs file ... 

Castro git describe: 23.01-36-gfc251492d
AMReX git describe: 23.02-34-g774f5443a
Microphysics git describe: 23.02-9-gfebab1f7

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

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

[STEP 1] Coarse TimeStep time: 0.361520698
[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.351511754
[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.331725688
[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.330761543
[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.351598573
[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.340835301
[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.312676991
[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.313657025
[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.314191825
[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.313477349
[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.010274679  seconds


Ending run at 22:41:31 UTC on 2023-02-12.
Run time = 3.521935335
Run time without initialization = 3.339588915

  Average number of zones advanced per microsecond: 0.785
  Average number of zones advanced per microsecond per rank: 0.098



TinyProfiler total time across processes [min...avg...max]: 3.521 ... 3.522 ... 3.522

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.2895     2.3479     2.3980  68.09%
Gravity::make_radial_gravity()                  21     0.3131     0.3227     0.3377   9.59%
Castro::do_advance_ctu()                        10    0.01844    0.07099     0.1297   3.68%
Castro::estTimeStep()                           21    0.02714    0.07301     0.1187   3.37%
Castro::initData()                               1    0.03834    0.07685     0.1152   3.27%
FabArray::setVal()                             143    0.06565    0.07404    0.08606   2.44%
amrex::Copy()                                  193    0.06249    0.07248    0.08045   2.28%
FabArray::ParallelCopy_finish()                 41    0.03739    0.05477    0.07838   2.23%
Castro::reset_internal_energy(Fab)            1008    0.04877    0.05004    0.05093   1.45%
Castro::normalize_species()                     62    0.04179    0.04271    0.04393   1.25%
FabArray::ParallelCopy_nowait()                 41    0.03516    0.04156    0.04392   1.25%
StateData::FillBoundary(geom)                   41    0.03485    0.03669    0.03845   1.09%
FillPatchIterator::Initialize                   41    0.02881    0.03334    0.03702   1.05%
Castro::construct_new_gravity_source()          10    0.02347    0.02745    0.03273   0.93%
Gravity::interpolate_monopole_grav()            21    0.02798    0.02905    0.03151   0.89%
Castro::initialize_do_advance()                 10    0.01192    0.01728     0.0204   0.58%
Castro::computeTemp()                           63    0.01958    0.02002    0.02032   0.58%
VisMF::Write(FabArray)                           2    0.01348    0.01401    0.01649   0.47%
FabArray::Saxpy()                               20    0.01232    0.01416    0.01509   0.43%
Castro::initialize_advance()                    10   0.008326    0.01125    0.01406   0.40%
MultiFab::contains_nan()                        20    0.01045    0.01122    0.01211   0.34%
FabArray::setDomainBndry()                      41   0.007953    0.01023    0.01164   0.33%
Castro::construct_old_gravity_source()          10   0.007486    0.01015    0.01083   0.31%
Amr::writePlotFile()                             2   0.006715   0.009459    0.01083   0.31%
Castro::initMFs()                                1   0.006834   0.006876   0.006922   0.20%
Castro::construct_new_source()                  50   0.005762   0.006311   0.006721   0.19%
main()                                           1   0.005239   0.006227   0.006653   0.19%
Castro::enforce_min_density()                   62    0.00572   0.006005   0.006316   0.18%
StateData::define()                              4   0.005748   0.005782   0.005825   0.17%
Castro::swap_state_time_levels()                10   0.003329   0.004351   0.004704   0.13%
Gravity::get_new_grav_vector()                  11   0.002974   0.003659   0.003986   0.11%
Gravity::get_old_grav_vector()                  10   0.001724   0.002412   0.002843   0.08%
Amr::coarseTimeStep()                           10   0.001261   0.002058   0.002651   0.08%
Castro::finalize_do_advance()                   10   0.000633    0.00153   0.001894   0.05%
Castro::buildMetrics()                           1    0.00163   0.001651   0.001677   0.05%
Castro::expand_state()                          10  0.0006499  0.0008656   0.001093   0.03%
Amr::timeStep()                                 10  0.0001725  0.0002544  0.0004719   0.01%
Amr::InitAmr()                                   1  0.0002857  0.0003177  0.0004167   0.01%
Castro::reset_internal_energy(MultiFab)         63   0.000273  0.0002856  0.0002964   0.01%
Castro::clean_state()                           62  0.0001341  0.0001482  0.0001563   0.00%
Castro::Castro()                                 1    8.6e-05  0.0001011  0.0001492   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.034e-06  9.778e-05  0.0001228   0.00%
Castro::enforce_consistent_e()                   1  8.132e-05   9.12e-05  0.0001181   0.00%
Amr::writeSmallPlotFile()                        1  3.195e-05  6.648e-05  0.0001068   0.00%
Castro::finalize_advance()                      10   7.56e-05  9.255e-05  0.0001043   0.00%
StateDataPhysBCFunct::()                        41  7.723e-05  8.729e-05  9.995e-05   0.00%
FabArray::ParallelCopy()                        41  6.952e-05  7.953e-05  8.753e-05   0.00%
Castro::construct_old_source()                  50  4.181e-05  6.616e-05  8.156e-05   0.00%
FillPatchSingleLevel                            41  6.074e-05  7.145e-05  7.999e-05   0.00%
Castro::do_old_sources()                        10  4.321e-05   6.09e-05  7.681e-05   0.00%
Castro::do_new_sources()                        10  5.065e-05  6.236e-05  7.546e-05   0.00%
Amr::FinalizeInit()                              1  4.332e-06   1.26e-05  6.781e-05   0.00%
Amr::defBaseLevel()                              1  3.141e-05  4.475e-05  6.399e-05   0.00%
FabArrayBase::getCPC()                          41   3.95e-05  4.956e-05  5.919e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.777e-05  4.569e-05  5.744e-05   0.00%
Castro::construct_new_gravity()                 10  3.371e-05  4.228e-05  5.477e-05   0.00%
Castro::apply_source_to_state()                 20  2.947e-05  4.254e-05  5.204e-05   0.00%
Castro::construct_old_gravity()                 10  3.336e-05  4.248e-05  4.961e-05   0.00%
Castro::post_timestep()                         10  1.714e-05  2.828e-05  4.145e-05   0.00%
Castro::advance()                               10  3.395e-05  3.776e-05  4.034e-05   0.00%
Castro::computeNewDt()                           9  7.667e-06  1.692e-05  3.967e-05   0.00%
Castro::enforce_speed_limit()                   62  2.447e-05   2.79e-05  3.013e-05   0.00%
AmrLevel::AmrLevel(dm)                           1   1.69e-05  2.075e-05  2.666e-05   0.00%
FabArrayBase::CPC::define()                      2  2.104e-05  2.318e-05  2.563e-05   0.00%
Castro::check_for_nan()                         20  1.895e-05  2.063e-05  2.488e-05   0.00%
Amr::initSubcycle()                              1  8.987e-06  1.074e-05  1.971e-05   0.00%
Gravity::set_mass_offset()                      10  8.687e-06    1.1e-05  1.515e-05   0.00%
Gravity::swapTimeLevels()                       10  7.499e-06  1.223e-05  1.453e-05   0.00%
Castro::create_source_corrector()               10  6.525e-06  9.441e-06  1.093e-05   0.00%
Castro::FluxRegCrseInit                         10  7.212e-06  8.526e-06  9.583e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1   3.52e-06  4.922e-06  9.219e-06   0.00%
Amr::initialInit()                               1   1.14e-06  3.097e-06  8.446e-06   0.00%
Castro::retry_advance_ctu()                     10  5.048e-06  5.616e-06  6.088e-06   0.00%
Castro::post_init()                              1   3.31e-06  3.903e-06  5.361e-06   0.00%
Castro::computeInitialDt()                       2  3.391e-06  4.039e-06  4.754e-06   0.00%
Castro::post_regrid()                            1  2.025e-06  2.963e-06  4.533e-06   0.00%
Amr::InitializeInit()                            1  1.988e-06  2.679e-06   4.31e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.127e-06  2.465e-06  2.905e-06   0.00%
Amr::init()                                      1  1.873e-06  2.062e-06  2.223e-06   0.00%
DistributionMapping::Distribute()                1   8.15e-07  1.023e-06  1.227e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.521      3.522      3.522 100.00%
Amr::coarseTimeStep()                           10      3.323      3.323      3.323  94.34%
Amr::timeStep()                                 10      3.311      3.311      3.312  94.03%
Castro::advance()                               10      3.294      3.295      3.296  93.57%
Castro::subcycle_advance_ctu()                  10      3.207      3.215      3.222  91.49%
Castro::do_advance_ctu()                        10      3.207      3.215      3.222  91.49%
Castro::construct_ctu_hydro_source()            10       2.29      2.348      2.398  68.09%
Gravity::make_radial_gravity()                  21     0.3299     0.3397     0.3527  10.01%
Gravity::get_new_grav_vector()                  11     0.2259      0.227     0.2276   6.46%
Castro::construct_old_gravity()                 10     0.1935     0.2005      0.211   5.99%
Gravity::get_old_grav_vector()                  10     0.1923     0.1996     0.2104   5.97%
Castro::construct_new_gravity()                 10     0.2015     0.2022     0.2029   5.76%
FillPatchIterator::Initialize                   41     0.1633     0.1769     0.1864   5.29%
Amr::init()                                      1     0.1818     0.1819     0.1819   5.16%
Castro::initialize_do_advance()                 10     0.1566     0.1677     0.1764   5.01%
Amr::initialInit()                               1     0.1657      0.166     0.1662   4.72%
FillPatchSingleLevel                            41     0.1164     0.1333     0.1489   4.23%
Amr::InitializeInit()                            1    0.06019    0.09876     0.1373   3.90%
Amr::defBaseLevel()                              1    0.06018    0.09876     0.1373   3.90%
Castro::expand_state()                          10      0.107     0.1201     0.1292   3.67%
Castro::clean_state()                           62     0.1165     0.1183     0.1209   3.43%
Castro::initData()                               1    0.04253    0.08102     0.1195   3.39%
Castro::estTimeStep()                           21    0.02714    0.07301     0.1187   3.37%
FabArray::ParallelCopy()                        41    0.08055    0.09648     0.1137   3.23%
Amr::FinalizeInit()                              1    0.02838    0.06721     0.1058   3.01%
FabArray::setVal()                             143    0.06565    0.07404    0.08606   2.44%
Castro::initialize_advance()                    10    0.06886    0.07587    0.08463   2.40%
Castro::do_new_sources()                        10    0.06055    0.06896    0.08099   2.30%
amrex::Copy()                                  193    0.06249    0.07248    0.08045   2.28%
Castro::computeInitialDt()                       2   0.002399    0.04093    0.07953   2.26%
FabArray::ParallelCopy_finish()                 41    0.03739    0.05477    0.07838   2.23%
Castro::computeTemp()                           63    0.06904    0.07035    0.07134   2.03%
Castro::reset_internal_energy(MultiFab)         63    0.04906    0.05033    0.05122   1.45%
Castro::reset_internal_energy(Fab)            1008    0.04877    0.05004    0.05093   1.45%
Castro::do_old_sources()                        10    0.04044    0.04531    0.04669   1.33%
FabArray::ParallelCopy_nowait()                 41    0.03522    0.04163    0.04399   1.25%
Castro::normalize_species()                     62    0.04179    0.04271    0.04393   1.25%
Castro::construct_new_source()                  50    0.02952    0.03376    0.03944   1.12%
StateDataPhysBCFunct::()                        41    0.03494    0.03677    0.03855   1.09%
StateData::FillBoundary(geom)                   41    0.03485    0.03669    0.03845   1.09%
Castro::construct_new_gravity_source()          10    0.02347    0.02745    0.03273   0.93%
Gravity::interpolate_monopole_grav()            21    0.02798    0.02905    0.03151   0.89%
Amr::writePlotFile()                             2    0.02605    0.02625    0.02655   0.75%
Castro::post_init()                              1    0.02546    0.02573    0.02598   0.74%
Castro::post_timestep()                         10    0.01593    0.01627     0.0166   0.47%
VisMF::Write(FabArray)                           2    0.01348    0.01401    0.01649   0.47%
Castro::apply_source_to_state()                 20    0.01235     0.0142    0.01514   0.43%
FabArray::Saxpy()                               20    0.01232    0.01416    0.01509   0.43%
Castro::check_for_nan()                         20    0.01047    0.01124    0.01213   0.34%
MultiFab::contains_nan()                        20    0.01045    0.01122    0.01211   0.34%
Castro::Castro()                                 1    0.01165    0.01179    0.01204   0.34%
FabArray::setDomainBndry()                      41   0.007953    0.01023    0.01164   0.33%
Castro::construct_old_source()                  50   0.007527    0.01021    0.01091   0.31%
Castro::construct_old_gravity_source()          10   0.007486    0.01015    0.01083   0.31%
Castro::computeNewDt()                           9    0.00842    0.00848   0.008522   0.24%
Castro::initMFs()                                1   0.006834   0.006876   0.006922   0.20%
Castro::enforce_min_density()                   62    0.00572   0.006005   0.006316   0.18%
AmrLevel::AmrLevel(dm)                           1   0.005765   0.005803   0.005842   0.17%
StateData::define()                              4   0.005748   0.005782   0.005825   0.17%
Castro::swap_state_time_levels()                10   0.003329   0.004351   0.004704   0.13%
Castro::finalize_advance()                      10   0.002923   0.003697   0.004463   0.13%
Castro::finalize_do_advance()                   10   0.000633    0.00153   0.001894   0.05%
Castro::buildMetrics()                           1    0.00177   0.001794   0.001819   0.05%
Castro::post_regrid()                            1   0.001556   0.001622    0.00168   0.05%
Amr::InitAmr()                                   1  0.0002947  0.0003285  0.0004258   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.142e-05  0.0001037   0.000133   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.034e-06  9.778e-05  0.0001228   0.00%
Castro::enforce_consistent_e()                   1  8.132e-05   9.12e-05  0.0001181   0.00%
Amr::writeSmallPlotFile()                        1  3.195e-05  6.648e-05  0.0001068   0.00%
FabArrayBase::getCPC()                          41  6.161e-05  7.274e-05  8.207e-05   0.00%
Castro::enforce_speed_limit()                   62  2.447e-05   2.79e-05  3.013e-05   0.00%
FabArrayBase::CPC::define()                      2  2.104e-05  2.318e-05  2.563e-05   0.00%
Amr::initSubcycle()                              1  8.987e-06  1.074e-05  1.971e-05   0.00%
Gravity::set_mass_offset()                      10  8.687e-06    1.1e-05  1.515e-05   0.00%
Gravity::swapTimeLevels()                       10  7.499e-06  1.223e-05  1.453e-05   0.00%
Castro::create_source_corrector()               10  6.525e-06  9.441e-06  1.093e-05   0.00%
Castro::FluxRegCrseInit                         10  7.212e-06  8.526e-06  9.583e-06   0.00%
Castro::retry_advance_ctu()                     10  5.048e-06  5.616e-06  6.088e-06   0.00%
Castro::FluxRegFineAdd()                        10  2.127e-06  2.465e-06  2.905e-06   0.00%
DistributionMapping::Distribute()                1   8.15e-07  1.023e-06  1.227e-06   0.00%
-------------------------------------------------------------------------------------------

Cpu (amrex_mempool) Memory Usage:
---------------------------------------------------------------------------------------------------------------------
Name                            Nalloc  Nfree  AvgMem min  AvgMem avg  AvgMem max  MaxMem min  MaxMem avg  MaxMem max
---------------------------------------------------------------------------------------------------------------------
The_Pinned_Arena::Initialize()       8      8      28   B      30   B      37   B    8192 KiB    8192 KiB    8192 KiB
amrex_mempool_init()                 8      8      15 KiB      15 KiB      17 KiB    8192 KiB    8192 KiB    8192 KiB
---------------------------------------------------------------------------------------------------------------------

Unused ParmParse Variables:
  [TOP]::amr.ref_ratio(nvals = 4)  :: [2, 2, 2, 2]
  [TOP]::amr.regrid_int(nvals = 4)  :: [2, 2, 2, 2]

AMReX (23.02-34-g774f5443a770) finalized