MPI initialized with 8 MPI processes
MPI initialized with thread support level 3
AMReX (22.06-13-g7b335c5f9617) initialized

Starting run at 05:38:11 UTC on 2022-06-09.
Successfully read inputs file ... 

Castro git describe: 22.06-9-g26873b1cc
AMReX git describe: 22.06-13-g7b335c5f9
Microphysics git describe: 22.06-1-gb8e427c1

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

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

[STEP 1] Coarse TimeStep time: 0.359832558
[STEP 1] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.336650933
[STEP 2] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.337234821
[STEP 3] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.337063451
[STEP 4] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.338518882
[STEP 5] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.352859629
[STEP 6] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.333209064
[STEP 7] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.334574067
[STEP 8] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.334393169
[STEP 9] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

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.333800334
[STEP 10] FAB kilobyte spread across MPI nodes: [58394 ... 58394]

STEP = 10 TIME = 0.000571255455 DT = 7.045732848e-05

PLOTFILE: file = dustcollapse-3d-monopole_plt00010
Write plotfile time = 0.009868568  seconds


Ending run at 05:38:14 UTC on 2022-06-09.
Run time = 3.589766313
Run time without initialization = 3.414351577

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



TinyProfiler total time across processes [min...avg...max]: 3.587 ... 3.589 ... 3.59

-------------------------------------------------------------------------------------------
Name                                        NCalls  Excl. Min  Excl. Avg  Excl. Max   Max %
-------------------------------------------------------------------------------------------
Castro::construct_ctu_hydro_source()            10     2.1048     2.3739     2.4652  68.67%
Castro::do_advance_ctu()                        10    0.03748     0.1288     0.4006  11.16%
Gravity::make_radial_gravity()                  21      0.351     0.3608     0.3883  10.82%
FabArray::ParallelCopy_finish()                 41    0.04731    0.06492     0.1306   3.64%
Castro::initData()                               1    0.03885    0.07775     0.1116   3.11%
Castro::estTimeStep()                           21    0.02187    0.06027     0.1067   2.97%
FabArray::setVal()                             143    0.06002    0.06897     0.0715   1.99%
Castro::reset_internal_energy(Fab)            1008    0.04638     0.0495    0.05081   1.42%
FabArray::ParallelCopy_nowait()                 41    0.02521    0.04258    0.04798   1.34%
StateData::FillBoundary(geom)                   41    0.03592    0.04172     0.0463   1.29%
Castro::normalize_species()                     62    0.03965    0.04161    0.04239   1.18%
FillPatchIterator::Initialize                   41    0.01918    0.03203    0.03699   1.03%
Gravity::interpolate_monopole_grav()            21    0.02641    0.02748    0.02828   0.79%
Castro::construct_new_gravity_source()          10    0.01759    0.02281     0.0242   0.67%
Castro::expand_state()                          10   0.009623    0.01909    0.02236   0.62%
Castro::computeTemp()                           63    0.01762     0.0187    0.01961   0.55%
Castro::initialize_do_advance()                 10   0.008317    0.01642    0.01816   0.51%
MultiFab::Saxpy()                               20   0.008538    0.01442    0.01639   0.46%
FabArray::setDomainBndry()                      41   0.004852    0.01222    0.01596   0.44%
VisMF::Write(FabArray)                           2    0.01184    0.01244    0.01498   0.42%
Amr::writePlotFile()                             2   0.009206    0.01185    0.01257   0.35%
Gravity::get_new_grav_vector()                  11   0.008488    0.01129    0.01226   0.34%
Castro::construct_old_gravity_source()          10   0.004886    0.01006     0.0118   0.33%
MultiFab::contains_nan()                        20   0.009377    0.01073    0.01121   0.31%
Gravity::get_old_grav_vector()                  10   0.005545   0.009772     0.0109   0.30%
Castro::initialize_advance()                    10   0.006308   0.009607    0.01024   0.29%
Castro::enforce_min_density()                   62   0.005883   0.006775   0.007125   0.20%
Castro::initMFs()                                1     0.0055     0.0059   0.007042   0.20%
Castro::construct_new_source()                  50   0.004855   0.006482   0.006995   0.19%
main()                                           1    0.00323   0.004835   0.005574   0.16%
StateData::define()                              4    0.00459    0.00475    0.00515   0.14%
Castro::swap_state_time_levels()                10   0.002528   0.003636   0.004337   0.12%
Amr::coarseTimeStep()                           10   0.001313   0.002111   0.003429   0.10%
Castro::buildMetrics()                           1   0.001296   0.001352   0.001478   0.04%
Castro::finalize_do_advance()                   10  0.0005413   0.001104   0.001262   0.04%
Amr::timeStep()                                 10  0.0001715   0.000249  0.0005608   0.02%
Amr::InitAmr()                                   1  0.0002751  0.0002982  0.0003966   0.01%
Castro::reset_internal_energy(MultiFab)         63  0.0002829  0.0002955  0.0003273   0.01%
Castro::clean_state()                           62  0.0001325  0.0001596  0.0001772   0.00%
StateDataPhysBCFunct::()                        41  9.602e-05  0.0001236  0.0001388   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.505e-06  0.0001058   0.000131   0.00%
FabArray::ParallelCopy()                        41  6.176e-05  8.729e-05  9.751e-05   0.00%
Castro::construct_old_source()                  50  3.616e-05  7.321e-05  9.687e-05   0.00%
Castro::enforce_consistent_e()                   1   8.23e-05  8.677e-05  9.321e-05   0.00%
FillPatchSingleLevel                            41  5.512e-05  6.916e-05  9.028e-05   0.00%
Castro::do_new_sources()                        10  4.497e-05  6.798e-05  8.639e-05   0.00%
Castro::Castro()                                 1  4.953e-05  6.603e-05  8.599e-05   0.00%
Castro::do_old_sources()                        10  3.741e-05  6.488e-05  7.883e-05   0.00%
Amr::FinalizeInit()                              1  4.302e-06  1.348e-05  7.246e-05   0.00%
Castro::construct_old_gravity()                 10  3.504e-05  5.525e-05  7.179e-05   0.00%
Amr::writeSmallPlotFile()                        1   3.09e-05  5.267e-05  7.001e-05   0.00%
Castro::finalize_advance()                      10  4.669e-05  5.145e-05  6.536e-05   0.00%
FabArrayBase::getCPC()                          41  4.074e-05  5.222e-05  5.924e-05   0.00%
Castro::subcycle_advance_ctu()                  10  3.174e-05  4.784e-05  5.862e-05   0.00%
Castro::apply_source_to_state()                 20      3e-05   4.82e-05  5.674e-05   0.00%
Castro::advance()                               10  3.832e-05  4.401e-05   5.46e-05   0.00%
Castro::construct_new_gravity()                 10  3.484e-05  4.398e-05  4.742e-05   0.00%
Amr::defBaseLevel()                              1  2.585e-05  3.232e-05  4.487e-05   0.00%
Castro::computeNewDt()                           9  7.878e-06  2.035e-05  4.258e-05   0.00%
AmrLevel::AmrLevel(dm)                           1  1.608e-05  2.663e-05  3.251e-05   0.00%
Castro::enforce_speed_limit()                   62  2.182e-05  2.671e-05  3.113e-05   0.00%
FabArrayBase::CPC::define()                      2  2.333e-05  2.558e-05   2.88e-05   0.00%
Castro::post_timestep()                         10  1.199e-05  1.594e-05  2.825e-05   0.00%
Castro::check_for_nan()                         20  2.159e-05  2.495e-05  2.756e-05   0.00%
Gravity::swapTimeLevels()                       10  6.072e-06  1.147e-05    1.5e-05   0.00%
Castro::FluxRegCrseInit                         10  5.919e-06  9.213e-06   1.47e-05   0.00%
Gravity::set_mass_offset()                      10  6.557e-06  1.041e-05  1.461e-05   0.00%
Castro::post_init()                              1  8.296e-06  1.042e-05  1.453e-05   0.00%
Castro::create_source_corrector()               10  5.957e-06  9.829e-06  1.226e-05   0.00%
Amr::initSubcycle()                              1  8.016e-06  8.936e-06  1.081e-05   0.00%
Castro::retry_advance_ctu()                     10  3.679e-06  6.215e-06  1.035e-05   0.00%
Amr::InitializeInit()                            1  1.899e-06  2.795e-06  6.129e-06   0.00%
DistributionMapping::SFCProcessorMapDoIt()       1  3.657e-06  4.415e-06  6.079e-06   0.00%
Castro::computeInitialDt()                       2  3.835e-06  4.643e-06  5.706e-06   0.00%
Castro::post_regrid()                            1  2.095e-06  2.968e-06  4.308e-06   0.00%
Amr::init()                                      1  1.888e-06  2.413e-06  2.899e-06   0.00%
Castro::FluxRegFineAdd()                        10  1.923e-06   2.42e-06  2.765e-06   0.00%
Amr::initialInit()                               1    1.1e-06  1.401e-06  1.909e-06   0.00%
DistributionMapping::Distribute()                1      9e-07  1.126e-06  1.682e-06   0.00%
-------------------------------------------------------------------------------------------

-------------------------------------------------------------------------------------------
Name                                        NCalls  Incl. Min  Incl. Avg  Incl. Max   Max %
-------------------------------------------------------------------------------------------
main()                                           1      3.587      3.589       3.59 100.00%
Amr::coarseTimeStep()                           10      3.399      3.399      3.399  94.68%
Amr::timeStep()                                 10      3.385      3.387      3.389  94.40%
Castro::advance()                               10       3.37      3.371      3.372  93.95%
Castro::subcycle_advance_ctu()                  10      3.299      3.301       3.31  92.21%
Castro::do_advance_ctu()                        10      3.299      3.301       3.31  92.21%
Castro::construct_ctu_hydro_source()            10      2.105      2.374      2.465  68.67%
Gravity::make_radial_gravity()                  21      0.351     0.3608     0.3883  10.82%
Castro::construct_old_gravity()                 10     0.2096     0.2171     0.2379   6.63%
Gravity::get_old_grav_vector()                  10     0.2087     0.2163     0.2373   6.61%
Gravity::get_new_grav_vector()                  11     0.2322     0.2336     0.2344   6.53%
FillPatchIterator::Initialize                   41     0.1876     0.1938      0.216   6.02%
Castro::construct_new_gravity()                 10     0.2058      0.207     0.2078   5.79%
FillPatchSingleLevel                            41     0.1369     0.1496      0.192   5.35%
Castro::initialize_do_advance()                 10     0.1625      0.177     0.1835   5.11%
Amr::init()                                      1     0.1749      0.175      0.175   4.88%
Amr::initialInit()                               1     0.1604     0.1607     0.1609   4.48%
FabArray::ParallelCopy()                        41    0.09469     0.1077     0.1559   4.34%
Castro::expand_state()                          10     0.1179     0.1304     0.1344   3.74%
Amr::InitializeInit()                            1    0.05788    0.09667      0.131   3.65%
Amr::defBaseLevel()                              1    0.05788    0.09667      0.131   3.65%
FabArray::ParallelCopy_finish()                 41    0.04731    0.06492     0.1306   3.64%
Castro::clean_state()                           62      0.109     0.1161     0.1185   3.30%
Castro::initData()                               1    0.04357    0.08192     0.1156   3.22%
Castro::estTimeStep()                           21    0.02187    0.06027     0.1067   2.97%
Amr::FinalizeInit()                              1    0.02966      0.064     0.1028   2.86%
Castro::computeInitialDt()                       2   0.001867    0.03619    0.07492   2.09%
Castro::initialize_advance()                    10    0.06111    0.07012    0.07244   2.02%
FabArray::setVal()                             143    0.06002    0.06897     0.0715   1.99%
Castro::computeTemp()                           63    0.06428    0.06849    0.07071   1.97%
Castro::do_new_sources()                        10    0.05088    0.06248    0.06567   1.83%
Castro::reset_internal_energy(MultiFab)         63    0.04667    0.04979     0.0511   1.42%
Castro::reset_internal_energy(Fab)            1008    0.04638     0.0495    0.05081   1.42%
Castro::do_old_sources()                        10    0.03215    0.04552    0.04902   1.37%
FabArray::ParallelCopy_nowait()                 41    0.02527    0.04266    0.04806   1.34%
StateDataPhysBCFunct::()                        41    0.03601    0.04184    0.04642   1.29%
StateData::FillBoundary(geom)                   41    0.03592    0.04172     0.0463   1.29%
Castro::normalize_species()                     62    0.03965    0.04161    0.04239   1.18%
Castro::construct_new_source()                  50    0.02244    0.02929    0.03119   0.87%
Gravity::interpolate_monopole_grav()            21    0.02641    0.02748    0.02828   0.79%
Castro::post_init()                              1    0.02715    0.02736    0.02755   0.77%
Amr::writePlotFile()                             2    0.02403    0.02429     0.0245   0.68%
Castro::construct_new_gravity_source()          10    0.01759    0.02281     0.0242   0.67%
Castro::apply_source_to_state()                 20   0.008568    0.01447    0.01644   0.46%
MultiFab::Saxpy()                               20   0.008538    0.01442    0.01639   0.46%
Castro::post_timestep()                         10    0.01476    0.01572    0.01636   0.46%
FabArray::setDomainBndry()                      41   0.004852    0.01222    0.01596   0.44%
VisMF::Write(FabArray)                           2    0.01184    0.01244    0.01498   0.42%
Castro::construct_old_source()                  50   0.004922    0.01014    0.01189   0.33%
Castro::construct_old_gravity_source()          10   0.004886    0.01006     0.0118   0.33%
Castro::check_for_nan()                         20   0.009398    0.01076    0.01123   0.31%
MultiFab::contains_nan()                        20   0.009377    0.01073    0.01121   0.31%
Castro::Castro()                                 1   0.009498   0.009828    0.01074   0.30%
Castro::computeNewDt()                           9    0.00774   0.008355   0.009671   0.27%
Castro::enforce_min_density()                   62   0.005883   0.006775   0.007125   0.20%
Castro::initMFs()                                1     0.0055     0.0059   0.007042   0.20%
AmrLevel::AmrLevel(dm)                           1   0.004622   0.004777   0.005183   0.14%
StateData::define()                              4    0.00459    0.00475    0.00515   0.14%
Castro::swap_state_time_levels()                10   0.002528   0.003636   0.004337   0.12%
Castro::post_regrid()                            1   0.001517   0.001626   0.001871   0.05%
Castro::buildMetrics()                           1   0.001417   0.001485   0.001633   0.05%
Castro::finalize_do_advance()                   10  0.0005413   0.001104   0.001262   0.04%
Amr::InitAmr()                                   1  0.0002836  0.0003071  0.0004048   0.01%
DistributionMapping::SFCProcessorMapDoIt()       1  1.232e-05  0.0001113  0.0001387   0.00%
DistributionMapping::LeastUsedCPUs()             1  7.505e-06  0.0001058   0.000131   0.00%
Castro::enforce_consistent_e()                   1   8.23e-05  8.677e-05  9.321e-05   0.00%
FabArrayBase::getCPC()                          41  6.407e-05   7.78e-05  8.754e-05   0.00%
Castro::finalize_advance()                      10  5.534e-05  6.308e-05  8.232e-05   0.00%
Amr::writeSmallPlotFile()                        1   3.09e-05  5.267e-05  7.001e-05   0.00%
Castro::enforce_speed_limit()                   62  2.182e-05  2.671e-05  3.113e-05   0.00%
FabArrayBase::CPC::define()                      2  2.333e-05  2.558e-05   2.88e-05   0.00%
Gravity::swapTimeLevels()                       10  6.072e-06  1.147e-05    1.5e-05   0.00%
Castro::FluxRegCrseInit                         10  5.919e-06  9.213e-06   1.47e-05   0.00%
Gravity::set_mass_offset()                      10  6.557e-06  1.041e-05  1.461e-05   0.00%
Castro::create_source_corrector()               10  5.957e-06  9.829e-06  1.226e-05   0.00%
Amr::initSubcycle()                              1  8.016e-06  8.936e-06  1.081e-05   0.00%
Castro::retry_advance_ctu()                     10  3.679e-06  6.215e-06  1.035e-05   0.00%
Castro::FluxRegFineAdd()                        10  1.923e-06   2.42e-06  2.765e-06   0.00%
DistributionMapping::Distribute()                1      9e-07  1.126e-06  1.682e-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.06-13-g7b335c5f9617) finalized