File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3822ms, 88.90% of 4299ms total Dropped 448 nodes (cum <= 21.50ms) flat flat% sum% cum cum% 0 0% 0% 4289ms 99.77% __libc_start_call_main 0 0% 0% 4289ms 99.77% __libc_start_main_impl 0 0% 0% 4289ms 99.77% _start 0 0% 0% 4286ms 99.70% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4286ms 99.70% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4286ms 99.70% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4286ms 99.70% main 0 0% 0% 4253ms 98.93% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4253ms 98.93% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4253ms 98.93% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4245ms 98.74% AbstractCardiacProblem::Solve 0 0% 0% 4236ms 98.53% AbstractDynamicLinearPdeSolver::Solve 18ms 0.42% 0.42% 2118ms 49.27% AbstractCardiacTissue::SolveCellSystems 0 0% 0.42% 1633ms 37.99% LinearSystem::Solve 0 0% 0.42% 1587ms 36.92% KSPSolve 2ms 0.047% 0.47% 1587ms 36.92% KSPSolve_Private 2ms 0.047% 0.51% 1552ms 36.10% KSPSolve_SYMMLQ 14ms 0.33% 0.84% 1200ms 27.91% AbstractCardiacCell::ComputeExceptVoltage 19ms 0.44% 1.28% 1064ms 24.75% AbstractOneStepIvpOdeSolver::InternalSolve 4ms 0.093% 1.37% 915ms 21.28% PCApply 107ms 2.49% 3.86% 907ms 21.10% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.86% 906ms 21.07% PCApply_BJacobi_Singleblock 0 0% 3.86% 795ms 18.49% KSPSolve_PREONLY (partial-inline) 281ms 6.54% 10.40% 790ms 18.38% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.023% 10.42% 750ms 17.45% PCApply_ICC 1ms 0.023% 10.44% 749ms 17.42% MatSolve 739ms 17.19% 27.63% 744ms 17.31% MatSolve_SeqSBAIJ_1_NaturalOrdering 20ms 0.47% 28.10% 735ms 17.10% AbstractCardiacTissue::UpdateCaches (inline) 6ms 0.14% 28.24% 718ms 16.70% MatMult 7ms 0.16% 28.40% 704ms 16.38% MatMult_SeqAIJ (partial-inline) 694ms 16.14% 44.55% 703ms 16.35% MatMult_SeqAIJ_Inode (inline) 303ms 7.05% 51.59% 690ms 16.05% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.023% 51.62% 671ms 15.61% KSP_PCApply.lto_priv.18 221ms 5.14% 56.76% 607ms 14.12% __GI___exp 56ms 1.30% 58.06% 436ms 10.14% BidomainSolver::SetupLinearSystem 332ms 7.72% 65.78% 332ms 7.72% __ieee754_exp_fma 0 0% 65.78% 323ms 7.51% KSPConvergedDefault 0 0% 65.78% 316ms 7.35% KSP_PCApply (partial-inline) 6ms 0.14% 65.92% 214ms 4.98% std::pow (inline) 18ms 0.42% 66.34% 205ms 4.77% __pow 11ms 0.26% 66.60% 186ms 4.33% __ieee754_pow_fma 91ms 2.12% 68.71% 135ms 3.14% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.023% 68.74% 114ms 2.65% MatNullSpaceRemove 109ms 2.54% 71.27% 109ms 2.54% [libchaste_heart.so] 90ms 2.09% 73.37% 90ms 2.09% log_inline (inline) 80ms 1.86% 75.23% 84ms 1.95% exp_inline (inline) 0 0% 75.23% 80ms 1.86% VecDuplicate 14ms 0.33% 75.55% 76ms 1.77% MPI_Wtime 24ms 0.56% 76.11% 73ms 1.70% TimeStepper::TimeStepper 0 0% 76.11% 64ms 1.49% VecMDot 2ms 0.047% 76.16% 64ms 1.49% VecMXDot_Private 62ms 1.44% 77.60% 62ms 1.44% [[vdso]] 62ms 1.44% 79.04% 62ms 1.44% [libm.so.6] 0 0% 79.04% 62ms 1.44% __GI___clock_gettime 57ms 1.33% 80.37% 57ms 1.33% TimeStepper::CalculateNextTime 10ms 0.23% 80.60% 56ms 1.30% PetscLogEventBeginDefault 5ms 0.12% 80.72% 53ms 1.23% VecCopy 5ms 0.12% 80.83% 49ms 1.14% VecMAXPY 48ms 1.12% 81.95% 48ms 1.12% ddot_ 1ms 0.023% 81.97% 44ms 1.02% VecDuplicate_MPI 0 0% 81.97% 43ms 1.00% AbstractBidomainSolver::FinaliseLinearSystem 12ms 0.28% 82.25% 43ms 1.00% PetscLogEventEndDefault 5ms 0.12% 82.37% 42ms 0.98% AbstractBidomainSolver::CheckCompatibilityCondition 41ms 0.95% 83.32% 42ms 0.98% VecMDot_Seq 2ms 0.047% 83.37% 41ms 0.95% VecNorm 38ms 0.88% 84.25% 38ms 0.88% __memcpy_evex_unaligned_erms 1ms 0.023% 84.28% 37ms 0.86% VecDot 0 0% 84.28% 36ms 0.84% PetscMemcpy (inline) 0 0% 84.28% 36ms 0.84% VecCreate 0 0% 84.28% 36ms 0.84% VecDuplicate_Seq 12ms 0.28% 84.55% 35ms 0.81% AbstractCardiacCell::GetVoltage 3ms 0.07% 84.62% 33ms 0.77% VecAXPY 30ms 0.7% 85.32% 33ms 0.77% VecMAXPY_Seq 10ms 0.23% 85.55% 31ms 0.72% AbstractCardiacCell::SetVoltage (partial-inline) 1ms 0.023% 85.58% 31ms 0.72% PetscMallocA 13ms 0.3% 85.88% 31ms 0.72% TimeStepper::AdvanceOneTimeStep 0 0% 85.88% 31ms 0.72% VecDestroy 0 0% 85.88% 31ms 0.72% VecDot_Seq 0 0% 85.88% 31ms 0.72% VecNorm_Seq 1ms 0.023% 85.90% 31ms 0.72% VecXDot_Seq_Private.constprop.0 17ms 0.4% 86.30% 30ms 0.7% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.023% 86.32% 30ms 0.7% PetscMallocAlign 0 0% 86.32% 30ms 0.7% VecCopy_Seq (partial-inline) 30ms 0.7% 87.02% 30ms 0.7% [libchaste_ode.so] 20ms 0.47% 87.49% 29ms 0.67% AbstractOneStepIvpOdeSolver::Solve 0 0% 87.49% 28ms 0.65% CxxTest::RealWorldDescription::setUp 1ms 0.023% 87.51% 28ms 0.65% PetscInitialize 0 0% 87.51% 28ms 0.65% PetscSetup::setUpWorld 0 0% 87.51% 28ms 0.65% PetscSetupUtils::CommonSetup 0 0% 87.51% 28ms 0.65% PetscSetupUtils::InitialisePetsc 17ms 0.4% 87.90% 27ms 0.63% AbstractParameterisedSystem::GetAnyVariable 0 0% 87.90% 27ms 0.63% PMPI_Init_thread 0 0% 87.90% 27ms 0.63% ompi_mpi_init 6ms 0.14% 88.04% 26ms 0.6% std::__cxx11::basic_string::basic_string 0 0% 88.04% 25ms 0.58% VecNorm_MPI 1ms 0.023% 88.07% 25ms 0.58% VecNorm_MPI_Default.constprop.0 0 0% 88.07% 24ms 0.56% 0 0% 88.07% 24ms 0.56% VecAXPY_Seq 9ms 0.21% 88.28% 24ms 0.56% __GI___libc_malloc 24ms 0.56% 88.83% 24ms 0.56% daxpy_ 2ms 0.047% 88.88% 22ms 0.51% PMPI_Comm_get_attr 0 0% 88.88% 22ms 0.51% VecSetType 1ms 0.023% 88.90% 22ms 0.51% __posix_memalign