File: TestConvergenceEfficiency Type: cpu Showing nodes accounting for 135177ms, 93.70% of 144270ms total Dropped 1054 nodes (cum <= 721.35ms) flat flat% sum% cum cum% 0 0% 0% 144249ms 100% __libc_start_call_main 0 0% 0% 144249ms 100% __libc_start_main_impl 0 0% 0% 144249ms 100% _start 0 0% 0% 144247ms 100% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 144247ms 100% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 144247ms 100% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 144247ms 100% main 0 0% 0% 144214ms 100% CxxTest::RealTestDescription::run 0 0% 0% 144214ms 100% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 144214ms 100% CxxTest::TestRunner::runTest (inline) 0 0% 0% 144214ms 100% TestConvergenceEfficiency::Test3DSpace10Efficiency (inline) 0 0% 0% 144214ms 100% TestDescription_TestConvergenceEfficiency_Test3DSpace10Efficiency::runTest (inline) 0 0% 0% 144213ms 100% AbstractConvergenceTester::Converge 4ms 0.0028% 0.0028% 143201ms 99.26% AbstractCardiacProblem::Solve 5ms 0.0035% 0.0062% 142755ms 98.95% AbstractDynamicLinearPdeSolver::Solve 1ms 0.00069% 0.0069% 98385ms 68.20% LinearSystem::Solve 2ms 0.0014% 0.0083% 98045ms 67.96% KSPSolve 4ms 0.0028% 0.011% 98043ms 67.96% KSPSolve_Private 7ms 0.0049% 0.016% 97789ms 67.78% KSPSolve_SYMMLQ 4ms 0.0028% 0.019% 67762ms 46.97% MatMult 326ms 0.23% 0.24% 67758ms 46.97% MatMult_SeqAIJ (partial-inline) 66901ms 46.37% 46.62% 67755ms 46.96% MatMult_SeqAIJ_Inode (inline) 3ms 0.0021% 46.62% 32096ms 22.25% PCApply 7ms 0.0049% 46.62% 32090ms 22.24% PCApply_BJacobi_Singleblock 2ms 0.0014% 46.63% 29664ms 20.56% KSPSolve_PREONLY (partial-inline) 161ms 0.11% 46.74% 29011ms 20.11% AbstractCardiacTissue::SolveCellSystems 2ms 0.0014% 46.74% 28431ms 19.71% PCApply_ICC 5ms 0.0035% 46.74% 28429ms 19.71% MatSolve 28021ms 19.42% 66.16% 28424ms 19.70% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 2ms 0.0014% 66.17% 27816ms 19.28% KSP_PCApply.lto_priv.18 470ms 0.33% 66.49% 23057ms 15.98% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 4032ms 2.79% 69.29% 19528ms 13.54% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 820ms 0.57% 69.85% 14486ms 10.04% BidomainSolver::SetupLinearSystem 1082ms 0.75% 70.60% 12772ms 8.85% CardiacNewtonSolver::Solve 4599ms 3.19% 73.79% 8350ms 5.79% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 1ms 0.00069% 73.79% 6301ms 4.37% KSP_PCApply (partial-inline) 6ms 0.0042% 73.80% 5581ms 3.87% KSPConvergedDefault 53ms 0.037% 73.83% 5199ms 3.60% AbstractCardiacTissue::UpdateCaches (inline) 1139ms 0.79% 74.62% 4916ms 3.41% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 3420ms 2.37% 76.99% 3420ms 2.37% __ieee754_log_fma 7ms 0.0049% 77.00% 3245ms 2.25% MatNullSpaceRemove 3232ms 2.24% 79.24% 3232ms 2.24% __memcpy_avx512_unaligned_erms 0 0% 79.24% 3193ms 2.21% PetscMemcpy (inline) 2122ms 1.47% 80.71% 2982ms 2.07% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 0 0% 80.71% 2972ms 2.06% boost::numeric::ublas::norm_inf (inline) 2532ms 1.76% 82.46% 2972ms 2.06% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 82.46% 2972ms 2.06% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 82.46% 2972ms 2.06% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 7ms 0.0049% 82.47% 2817ms 1.95% VecCopy 1ms 0.00069% 82.47% 2806ms 1.94% VecCopy_Seq (partial-inline) 13ms 0.009% 82.48% 2725ms 1.89% VecAXPY 0 0% 82.48% 2712ms 1.88% VecAXPY_Seq 2699ms 1.87% 84.35% 2699ms 1.87% daxpy_ 5ms 0.0035% 84.35% 2433ms 1.69% std::pow (inline) 171ms 0.12% 84.47% 2382ms 1.65% __pow 2379ms 1.65% 86.12% 2379ms 1.65% [libchaste_heart.so] 277ms 0.19% 86.31% 2290ms 1.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 281ms 0.19% 86.51% 2173ms 1.51% __ieee754_pow_fma 0 0% 86.51% 2034ms 1.41% VecMDot 11ms 0.0076% 86.52% 2034ms 1.41% VecMXDot_Private 2014ms 1.40% 87.91% 2018ms 1.40% VecMDot_Seq 0 0% 87.91% 1775ms 1.23% AbstractFeVolumeIntegralAssembler::DoAssemble 1766ms 1.22% 89.14% 1766ms 1.22% ddot_ 1648ms 1.14% 90.28% 1648ms 1.14% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1295ms 0.9% 91.18% 1502ms 1.04% exp_inline (inline) 14ms 0.0097% 91.19% 1494ms 1.04% AbstractFeVolumeIntegralAssembler::AssembleOnElement 0 0% 91.19% 1422ms 0.99% VecDot 2ms 0.0014% 91.19% 1422ms 0.99% VecDot_Seq 1ms 0.00069% 91.19% 1420ms 0.98% VecXDot_Seq_Private.constprop.0 0 0% 91.19% 1209ms 0.84% AbstractFeAssemblerInterface::AssembleMatrix (inline) 4ms 0.0028% 91.19% 1204ms 0.83% VecMAXPY 1194ms 0.83% 92.02% 1200ms 0.83% VecMAXPY_Seq 1124ms 0.78% 92.80% 1124ms 0.78% __memset_avx512_unaligned_erms 0 0% 92.80% 1112ms 0.77% PetscMemzero (inline) 0 0% 92.80% 1004ms 0.7% KSP_RemoveNullSpace (inline) 1ms 0.00069% 92.80% 949ms 0.66% boost::numeric::ublas::matrix_assign (inline) 385ms 0.27% 93.06% 947ms 0.66% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 141ms 0.098% 93.16% 944ms 0.65% boost::numeric::ublas::indexing_matrix_assign 0 0% 93.16% 889ms 0.62% AbstractTetrahedralMesh::ConstructRegularSlabMesh 0 0% 93.16% 889ms 0.62% CuboidMeshConstructor::Construct 1ms 0.00069% 93.16% 889ms 0.62% DistributedTetrahedralMesh::ConstructCuboid 1ms 0.00069% 93.16% 872ms 0.6% VecDuplicate 3ms 0.0021% 93.17% 803ms 0.56% Element::Element 5ms 0.0035% 93.17% 789ms 0.55% AbstractTetrahedralElement::CalculateJacobian 4ms 0.0028% 93.17% 766ms 0.53% BidomainAssembler::ComputeMatrixTerm 1ms 0.00069% 93.17% 751ms 0.52% AbstractTetrahedralElement::AbstractTetrahedralElement 7ms 0.0049% 93.18% 744ms 0.52% boost::numeric::ublas::c_matrix::c_matrix (inline) 8ms 0.0055% 93.18% 740ms 0.51% VecScale 3ms 0.0021% 93.19% 732ms 0.51% VecScale_Seq 726ms 0.5% 93.69% 726ms 0.5% dscal_ 13ms 0.009% 93.70% 724ms 0.5% PetscMallocA