File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3012ms, 89.51% of 3365ms total Dropped 416 nodes (cum <= 16.82ms) flat flat% sum% cum cum% 0 0% 0% 3357ms 99.76% __libc_start_call_main 0 0% 0% 3357ms 99.76% __libc_start_main_impl 0 0% 0% 3357ms 99.76% _start 0 0% 0% 3355ms 99.70% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 3355ms 99.70% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 3355ms 99.70% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 3355ms 99.70% main 0 0% 0% 3319ms 98.63% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3319ms 98.63% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3319ms 98.63% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 3312ms 98.42% AbstractCardiacProblem::Solve 0 0% 0% 3308ms 98.31% AbstractDynamicLinearPdeSolver::Solve 12ms 0.36% 0.36% 1598ms 47.49% AbstractCardiacTissue::SolveCellSystems 0 0% 0.36% 1299ms 38.60% LinearSystem::Solve 0 0% 0.36% 1280ms 38.04% KSPSolve 1ms 0.03% 0.39% 1280ms 38.04% KSPSolve_Private 0 0% 0.39% 1254ms 37.27% KSPSolve_SYMMLQ 8ms 0.24% 0.62% 900ms 26.75% AbstractCardiacCell::ComputeExceptVoltage 12ms 0.36% 0.98% 819ms 24.34% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.059% 1.04% 766ms 22.76% MatMult 281ms 8.35% 9.39% 763ms 22.67% MatMult_SeqAIJ 82ms 2.44% 11.83% 712ms 21.16% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.03% 11.86% 654ms 19.44% PCApply 0 0% 11.86% 652ms 19.38% PCApply_BJacobi_Singleblock 233ms 6.92% 18.78% 624ms 18.54% CellLuoRudy1991FromCellML::EvaluateYDerivatives 15ms 0.45% 19.23% 593ms 17.62% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 19.23% 571ms 16.97% KSPSolve_PREONLY (partial-inline) 240ms 7.13% 26.36% 559ms 16.61% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.059% 26.42% 537ms 15.96% MatSolve 0 0% 26.42% 537ms 15.96% PCApply_ICC 531ms 15.78% 42.20% 531ms 15.78% MatSolve_SeqSBAIJ_1_NaturalOrdering 476ms 14.15% 56.34% 484ms 14.38% MatMult_SeqAIJ_Inode (inline) 146ms 4.34% 60.68% 463ms 13.76% __GI___exp 0 0% 60.68% 455ms 13.52% KSP_PCApply.lto_priv.18 27ms 0.8% 61.49% 392ms 11.65% BidomainSolver::SetupLinearSystem 272ms 8.08% 69.57% 273ms 8.11% __ieee754_exp_fma 1ms 0.03% 69.60% 249ms 7.40% KSPConvergedDefault 0 0% 69.60% 246ms 7.31% KSP_PCApply (partial-inline) 6ms 0.18% 69.78% 164ms 4.87% std::pow (inline) 7ms 0.21% 69.99% 156ms 4.64% __pow 6ms 0.18% 70.16% 146ms 4.34% __ieee754_pow_fma 91ms 2.70% 72.87% 91ms 2.70% [libchaste_heart.so] 1ms 0.03% 72.90% 85ms 2.53% MatNullSpaceRemove 76ms 2.26% 75.16% 76ms 2.26% log_inline (inline) 44ms 1.31% 76.46% 66ms 1.96% CellLuoRudy1991FromCellML::VerifyStateVariables 53ms 1.58% 78.04% 62ms 1.84% exp_inline (inline) 12ms 0.36% 78.40% 57ms 1.69% MPI_Wtime 54ms 1.60% 80.00% 54ms 1.60% [libm.so.6] 0 0% 80.00% 52ms 1.55% VecDuplicate 0 0% 80.00% 52ms 1.55% VecMDot 5ms 0.15% 80.15% 52ms 1.55% VecMXDot_Private 44ms 1.31% 81.46% 44ms 1.31% [[vdso]] 0 0% 81.46% 44ms 1.31% __GI___clock_gettime 6ms 0.18% 81.63% 41ms 1.22% PetscLogEventEndDefault 38ms 1.13% 82.76% 38ms 1.13% TimeStepper::CalculateNextTime 36ms 1.07% 83.83% 36ms 1.07% ddot_ 1ms 0.03% 83.86% 34ms 1.01% VecDot 34ms 1.01% 84.87% 34ms 1.01% VecMDot_Seq 0 0% 84.87% 33ms 0.98% CxxTest::RealWorldDescription::setUp 0 0% 84.87% 33ms 0.98% PMPI_Init_thread 0 0% 84.87% 33ms 0.98% PetscInitialize 0 0% 84.87% 33ms 0.98% PetscSetup::setUpWorld 0 0% 84.87% 33ms 0.98% PetscSetupUtils::CommonSetup 0 0% 84.87% 33ms 0.98% PetscSetupUtils::InitialisePetsc 0 0% 84.87% 33ms 0.98% ompi_mpi_init 7ms 0.21% 85.08% 32ms 0.95% TimeStepper::AdvanceOneTimeStep 6ms 0.18% 85.26% 32ms 0.95% VecMAXPY 9ms 0.27% 85.53% 31ms 0.92% TimeStepper::TimeStepper 3ms 0.089% 85.62% 31ms 0.92% VecCopy 1ms 0.03% 85.65% 29ms 0.86% 7ms 0.21% 85.85% 28ms 0.83% AbstractCardiacCell::SetVoltage (partial-inline) 4ms 0.12% 85.97% 28ms 0.83% PetscLogEventBeginDefault 20ms 0.59% 86.57% 27ms 0.8% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.059% 86.63% 27ms 0.8% KSP_RemoveNullSpace (inline) 0 0% 86.63% 27ms 0.8% VecDot_Seq 0 0% 86.63% 27ms 0.8% VecDuplicate_Seq 0 0% 86.63% 27ms 0.8% VecXDot_Seq_Private.constprop.0 2ms 0.059% 86.69% 25ms 0.74% VecDuplicate_MPI 2ms 0.059% 86.75% 24ms 0.71% VecDestroy 0 0% 86.75% 24ms 0.71% orte_init 1ms 0.03% 86.78% 23ms 0.68% PetscMallocA 23ms 0.68% 87.46% 23ms 0.68% [libchaste_ode.so] 23ms 0.68% 88.14% 23ms 0.68% __ieee754_log_fma 0 0% 88.14% 22ms 0.65% PetscMallocAlign 1ms 0.03% 88.17% 22ms 0.65% VecAXPY 1ms 0.03% 88.20% 21ms 0.62% VecCreate 8ms 0.24% 88.44% 21ms 0.62% __GI___libc_malloc 0 0% 88.44% 20ms 0.59% VecNorm 18ms 0.53% 88.97% 18ms 0.53% VecMAXPY_Seq 17ms 0.51% 89.48% 17ms 0.51% __memcpy_avx512_unaligned_erms 1ms 0.03% 89.51% 17ms 0.51% __posix_memalign