MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (23.05-31-g2e1106e246c4) initialized

Starting run at 13:37:54 UTC on 2023-06-01.
Successfully read inputs file ... 

Castro git describe: 23.05-31-g95ff25350
AMReX git describe: 23.05-31-g2e1106e24
Microphysics git describe: 23.06

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

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

[STEP 1] Coarse TimeStep time: 0.387298795
[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.353024919
[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.363610212
[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.331609387
[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.36270724
[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.329874466
[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.31377127
[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.312059547
[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.313111712
[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.313264442
[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.010482972  seconds


Ending run at 13:37:58 UTC on 2023-06-01.
Run time = 3.645805989
Run time without initialization = 3.398129474

  Average number of zones advanced per microsecond: 0.771
  Average number of zones advanced per microsecond per rank: 0.096



TinyProfiler total time across processes [min...avg...max]: 3.644 ... 3.645 ... 3.646

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.3276     2.3649     2.3973  65.75%
Gravity::make_radial_gravity()                  21     0.3396      0.352     0.3644  10.00%
Castro::estTimeStep()                           21    0.03372      0.109     0.1677   4.60%
Castro::initData()                               1    0.03849    0.09028     0.1636   4.49%
Castro::do_advance_ctu()                        10    0.07147     0.1055     0.1454   3.99%
amrex::Copy()                                  193    0.06303    0.07285    0.08322   2.28%
FabArray::setVal()                             143    0.05894    0.06688    0.07845   2.15%
FabArray::ParallelCopy_finish()                 41    0.03702    0.05383    0.07792   2.14%
Castro::reset_internal_energy(Fab)            1008    0.04892    0.05105      0.053   1.45%
Castro::normalize_species()                     62    0.04098     0.0424    0.04361   1.20%
FabArray::ParallelCopy_nowait()                 41    0.03719    0.04127    0.04274   1.17%
StateData::FillBoundary(geom)                   41    0.03309    0.03628    0.04006   1.10%
FillPatchIterator::Initialize                   41    0.02943     0.0341     0.0358   0.98%
Gravity::interpolate_monopole_grav()            21    0.02774    0.02852    0.02986   0.82%
Castro::construct_new_gravity_source()          10    0.02235    0.02448     0.0272   0.75%
Castro::computeTemp()                           63    0.02004    0.02061    0.02114   0.58%
Castro::initialize_do_advance()                 10    0.01249     0.0176    0.01956   0.54%
VisMF::Write(FabArray)                           2    0.01387    0.01458    0.01722   0.47%
FabArray::Saxpy()                               20    0.01298    0.01405    0.01487   0.41%
Castro::initialize_advance()                    10   0.004109    0.01017     0.0139   0.38%
FabArray::setDomainBndry()                      41   0.008852    0.01167    0.01289   0.35%
MultiFab::contains_nan()                        20    0.01003    0.01109    0.01222   0.34%
Castro::construct_old_gravity_source()          10    0.00847    0.01011    0.01058   0.29%
Castro::initMFs()                                1   0.006891   0.008007    0.01044   0.29%
Amr::writePlotFile()                             2     0.0068   0.009477    0.01021   0.28%
StateData::define()                              4   0.005758   0.006642    0.00844   0.23%
main()                                           1   0.005168   0.006089   0.006631   0.18%
Castro::enforce_min_density()                   62   0.005729   0.006082   0.006386   0.18%
Castro::construct_new_source()                  50    0.00555   0.006086   0.006283   0.17%
Castro::swap_state_time_levels()                10   0.003387   0.004562   0.004761   0.13%
Gravity::get_new_grav_vector()                  11   0.002964   0.003768   0.004478   0.12%
Amr::coarseTimeStep()                           10   0.001512   0.002186   0.003029   0.08%
Gravity::get_old_grav_vector()                  10   0.001926   0.002398   0.002726   0.07%
Castro::buildMetrics()                           1   0.001651   0.001926   0.002443   0.07%
Castro::finalize_do_advance()                   10   0.000615   0.001489   0.001812   0.05%
Castro::expand_state()                          10  0.0006836  0.0008476   0.001041   0.03%
Amr::InitAmr()                                   1  0.0002689  0.0003547  0.0004958   0.01%
Amr::timeStep()                                 10  0.0001771  0.0002483  0.0004747   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002935   0.000315  0.0003362   0.01%
DistributionMapping::LeastUsedCPUs()             1  7.602e-06  0.0001862  0.0002704   0.01%
Castro::clean_state()                           62  0.0001493  0.0001621   0.000179   0.00%
Castro::enforce_consistent_e()                   1  7.953e-05  0.0001098  0.0001698   0.00%
StateDataPhysBCFunct::()                        41  7.986e-05  9.667e-05  0.0001333   0.00%
Castro::finalize_advance()                      10  7.819e-05  9.764e-05  0.0001075   0.00%
FabArray::ParallelCopy()                        41  6.992e-05  8.024e-05  9.621e-05   0.00%
FabArrayBase::getCPC()                          41  4.307e-05   5.81e-05  8.538e-05   0.00%
FillPatchSingleLevel                            41  6.623e-05  7.204e-05  8.127e-05   0.00%
Castro::Castro()                                 1  5.153e-05  6.721e-05  7.659e-05   0.00%
Castro::construct_old_source()                  50  5.378e-05   6.57e-05  7.621e-05   0.00%
Castro::subcycle_advance_ctu()                  10  4.187e-05  5.027e-05    7.5e-05   0.00%
Amr::FinalizeInit()                              1  3.936e-06  1.351e-05  7.187e-05   0.00%
Castro::do_new_sources()                        10  5.353e-05  6.523e-05   7.17e-05   0.00%
Amr::writeSmallPlotFile()                        1  2.506e-05  5.176e-05  7.129e-05   0.00%
Castro::do_old_sources()                        10  5.656e-05  6.526e-05  7.007e-05   0.00%
Amr::defBaseLevel()                              1  2.442e-05  3.623e-05  5.412e-05   0.00%
Castro::apply_source_to_state()                 20  3.659e-05  4.639e-05  5.323e-05   0.00%
Castro::construct_new_gravity()                 10  3.476e-05  4.451e-05  4.889e-05   0.00%
Castro::advance()                               10  3.801e-05  4.359e-05  4.757e-05   0.00%
Castro::enforce_speed_limit()                   62  2.401e-05  3.141e-05  4.709e-05   0.00%
Castro::construct_old_gravity()                 10  3.552e-05  4.008e-05  4.354e-05   0.00%
FabArrayBase::CPC::define()                      2  2.173e-05  2.524e-05  3.714e-05   0.00%
Castro::check_for_nan()                         20  2.351e-05  2.653e-05  3.049e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.235e-05  1.791e-05   2.57e-05   0.00%
Amr::initSubcycle()                              1  9.108e-06  1.141e-05  1.646e-05   0.00%
Castro::post_timestep()                         10  1.309e-05  1.424e-05  1.519e-05   0.00%
Gravity::swapTimeLevels()                       10  9.189e-06  1.227e-05  1.421e-05   0.00%
Gravity::set_mass_offset()                      10      1e-05  1.211e-05  1.415e-05   0.00%
Castro::FluxRegCrseInit                         10  8.418e-06  9.476e-06  1.287e-05   0.00%
Castro::computeNewDt()                           9  8.611e-06  1.074e-05  1.196e-05   0.00%
Castro::create_source_corrector()               10  7.833e-06  8.705e-06  9.398e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.709e-06  5.526e-06  8.618e-06   0.00%
Castro::retry_advance_ctu()                     10  5.122e-06  5.853e-06  7.039e-06   0.00%
Castro::post_regrid()                            1  2.648e-06  3.681e-06  6.559e-06   0.00%
Castro::post_init()                              1  2.789e-06  3.627e-06  4.578e-06   0.00%
Amr::InitializeInit()                            1  2.317e-06  2.983e-06  4.574e-06   0.00%
Castro::computeInitialDt()                       2  3.323e-06  4.054e-06  4.503e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.915e-06  2.254e-06  3.043e-06   0.00%
Amr::init()                                      1  1.531e-06  2.157e-06  2.677e-06   0.00%
Amr::initialInit()                               1  1.158e-06  1.874e-06  2.621e-06   0.00%
DistributionMapping::Distribute()                1   8.88e-07  1.153e-06  1.481e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.644      3.645      3.646 100.00%
Amr::coarseTimeStep()                           10      3.381      3.381      3.381  92.74%
Amr::timeStep()                                 10      3.368      3.369       3.37  92.43%
Castro::advance()                               10      3.352      3.353      3.354  91.98%
Castro::subcycle_advance_ctu()                  10      3.271      3.279       3.29  90.25%
Castro::do_advance_ctu()                        10      3.271      3.279       3.29  90.25%
Castro::construct_ctu_hydro_source()            10      2.328      2.365      2.397  65.75%
Gravity::make_radial_gravity()                  21     0.3559     0.3686     0.3817  10.47%
Amr::init()                                      1      0.247     0.2471     0.2472   6.78%
Castro::construct_old_gravity()                 10      0.216     0.2249     0.2354   6.46%
Gravity::get_old_grav_vector()                  10     0.2152     0.2242     0.2348   6.44%
Amr::initialInit()                               1     0.2307      0.231     0.2314   6.35%
Gravity::get_new_grav_vector()                  11     0.2273     0.2283     0.2301   6.31%
Castro::construct_new_gravity()                 10     0.1938     0.1947     0.1957   5.37%
Amr::InitializeInit()                            1    0.05922     0.1135     0.1926   5.28%
Amr::defBaseLevel()                              1    0.05922     0.1135     0.1926   5.28%
FillPatchIterator::Initialize                   41     0.1641     0.1775     0.1903   5.22%
Castro::initialize_do_advance()                 10     0.1597     0.1679     0.1751   4.80%
Amr::FinalizeInit()                              1    0.03806     0.1176     0.1717   4.71%
Castro::initData()                               1    0.04246    0.09467     0.1694   4.65%
Castro::estTimeStep()                           21    0.03372      0.109     0.1677   4.60%
FillPatchSingleLevel                            41      0.116     0.1317     0.1517   4.16%
Castro::computeInitialDt()                       2   0.002798    0.08213     0.1365   3.74%
Castro::expand_state()                          10     0.1084     0.1191     0.1275   3.50%
Castro::clean_state()                           62     0.1158     0.1195     0.1225   3.36%
FabArray::ParallelCopy()                        41    0.07797    0.09526     0.1153   3.16%
amrex::Copy()                                  193    0.06303    0.07285    0.08322   2.28%
Castro::initialize_advance()                    10    0.05837     0.0701    0.07847   2.15%
FabArray::setVal()                             143    0.05894    0.06688    0.07845   2.15%
FabArray::ParallelCopy_finish()                 41    0.03702    0.05383    0.07792   2.14%
Castro::computeTemp()                           63    0.06946    0.07197    0.07427   2.04%
Castro::do_new_sources()                        10    0.05928    0.06435    0.07246   1.99%
Castro::reset_internal_energy(MultiFab)         63    0.04922    0.05136    0.05331   1.46%
Castro::reset_internal_energy(Fab)            1008    0.04892    0.05105      0.053   1.45%
Castro::do_old_sources()                        10    0.04427    0.04573    0.04788   1.31%
Castro::normalize_species()                     62    0.04098     0.0424    0.04361   1.20%
FabArray::ParallelCopy_nowait()                 41    0.03727    0.04135    0.04283   1.17%
StateDataPhysBCFunct::()                        41    0.03318    0.03637    0.04016   1.10%
StateData::FillBoundary(geom)                   41    0.03309    0.03628    0.04006   1.10%
Castro::post_init()                              1    0.03364    0.03472    0.03532   0.97%
Castro::construct_new_source()                  50    0.02851    0.03057    0.03346   0.92%
Gravity::interpolate_monopole_grav()            21    0.02774    0.02852    0.02986   0.82%
Castro::construct_new_gravity_source()          10    0.02235    0.02448     0.0272   0.75%
Amr::writePlotFile()                             2    0.02641    0.02666    0.02686   0.74%
VisMF::Write(FabArray)                           2    0.01387    0.01458    0.01722   0.47%
Castro::post_timestep()                         10    0.01535    0.01596    0.01683   0.46%
Castro::apply_source_to_state()                 20    0.01302     0.0141    0.01493   0.41%
FabArray::Saxpy()                               20    0.01298    0.01405    0.01487   0.41%
Castro::Castro()                                 1    0.01061    0.01191    0.01473   0.40%
FabArray::setDomainBndry()                      41   0.008852    0.01167    0.01289   0.35%
Castro::check_for_nan()                         20    0.01006    0.01111    0.01224   0.34%
MultiFab::contains_nan()                        20    0.01003    0.01109    0.01222   0.34%
Castro::construct_old_source()                  50    0.00853    0.01018    0.01065   0.29%
Castro::construct_old_gravity_source()          10    0.00847    0.01011    0.01058   0.29%
Castro::initMFs()                                1   0.006891   0.008007    0.01044   0.29%
Castro::computeNewDt()                           9    0.00843   0.008479   0.008502   0.23%
AmrLevel::AmrLevel(dm)                           1   0.005777    0.00666   0.008466   0.23%
StateData::define()                              4   0.005758   0.006642    0.00844   0.23%
Castro::enforce_min_density()                   62   0.005729   0.006082   0.006386   0.18%
Castro::swap_state_time_levels()                10   0.003387   0.004562   0.004761   0.13%
Castro::finalize_advance()                      10   0.002906   0.003416    0.00402   0.11%
Castro::post_regrid()                            1   0.001525   0.001963   0.002882   0.08%
Castro::buildMetrics()                           1   0.001769   0.002078   0.002662   0.07%
Castro::finalize_do_advance()                   10   0.000615   0.001489   0.001812   0.05%
Amr::InitAmr()                                   1  0.0002786  0.0003661  0.0005123   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.418e-05  0.0001929  0.0002795   0.01%
DistributionMapping::LeastUsedCPUs()             1  7.602e-06  0.0001862  0.0002704   0.01%
Castro::enforce_consistent_e()                   1  7.953e-05  0.0001098  0.0001698   0.00%
FabArrayBase::getCPC()                          41   6.69e-05  8.334e-05  0.0001225   0.00%
Amr::writeSmallPlotFile()                        1  2.506e-05  5.176e-05  7.129e-05   0.00%
Castro::enforce_speed_limit()                   62  2.401e-05  3.141e-05  4.709e-05   0.00%
FabArrayBase::CPC::define()                      2  2.173e-05  2.524e-05  3.714e-05   0.00%
Amr::initSubcycle()                              1  9.108e-06  1.141e-05  1.646e-05   0.00%
Gravity::swapTimeLevels()                       10  9.189e-06  1.227e-05  1.421e-05   0.00%
Gravity::set_mass_offset()                      10      1e-05  1.211e-05  1.415e-05   0.00%
Castro::FluxRegCrseInit                         10  8.418e-06  9.476e-06  1.287e-05   0.00%
Castro::create_source_corrector()               10  7.833e-06  8.705e-06  9.398e-06   0.00%
Castro::retry_advance_ctu()                     10  5.122e-06  5.853e-06  7.039e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.915e-06  2.254e-06  3.043e-06   0.00%
DistributionMapping::Distribute()                1   8.88e-07  1.153e-06  1.481e-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      31   B      40   B      82   B    8192 KiB    8192 KiB    8192 KiB
---------------------------------------------------------------------------------------------------------------------

AMReX (23.05-31-g2e1106e246c4) finalized