File: TestConvergenceEfficiency Type: cpu Showing nodes accounting for 133248ms, 93.47% of 142557ms total Dropped 1050 nodes (cum <= 712.78ms) flat flat% sum% cum cum% 0 0% 0% 142535ms 100% __libc_start_call_main 0 0% 0% 142535ms 100% __libc_start_main_impl 0 0% 0% 142535ms 100% _start 0 0% 0% 142533ms 100% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 142533ms 100% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 142533ms 100% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 142533ms 100% main 0 0% 0% 142505ms 100% CxxTest::RealTestDescription::run 0 0% 0% 142505ms 100% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 142505ms 100% CxxTest::TestRunner::runTest (inline) 0 0% 0% 142505ms 100% TestConvergenceEfficiency::Test3DSpace10Efficiency (inline) 0 0% 0% 142505ms 100% TestDescription_TestConvergenceEfficiency_Test3DSpace10Efficiency::runTest (inline) 0 0% 0% 142504ms 100% AbstractConvergenceTester::Converge 3ms 0.0021% 0.0021% 141482ms 99.25% AbstractCardiacProblem::Solve 3ms 0.0021% 0.0042% 140994ms 98.90% AbstractDynamicLinearPdeSolver::Solve 6ms 0.0042% 0.0084% 96795ms 67.90% LinearSystem::Solve 1ms 0.0007% 0.0091% 96428ms 67.64% KSPSolve 6ms 0.0042% 0.013% 96427ms 67.64% KSPSolve_Private 13ms 0.0091% 0.022% 96122ms 67.43% KSPSolve_SYMMLQ 3ms 0.0021% 0.025% 66516ms 46.66% MatMult 305ms 0.21% 0.24% 66513ms 46.66% MatMult_SeqAIJ (partial-inline) 65540ms 45.97% 46.21% 66512ms 46.66% MatMult_SeqAIJ_Inode (inline) 3ms 0.0021% 46.22% 32215ms 22.60% PCApply 7ms 0.0049% 46.22% 32207ms 22.59% PCApply_BJacobi_Singleblock 2ms 0.0014% 46.22% 29697ms 20.83% KSPSolve_PREONLY (partial-inline) 168ms 0.12% 46.34% 28573ms 20.04% AbstractCardiacTissue::SolveCellSystems 5ms 0.0035% 46.34% 28546ms 20.02% PCApply_ICC 3ms 0.0021% 46.34% 28541ms 20.02% MatSolve 28117ms 19.72% 66.07% 28538ms 20.02% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 3ms 0.0021% 66.07% 27828ms 19.52% KSP_PCApply.lto_priv.18 484ms 0.34% 66.41% 22578ms 15.84% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 3362ms 2.36% 68.77% 19053ms 13.37% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 711ms 0.5% 69.27% 14745ms 10.34% BidomainSolver::SetupLinearSystem 1069ms 0.75% 70.02% 12777ms 8.96% CardiacNewtonSolver::Solve 4687ms 3.29% 73.30% 8410ms 5.90% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 1ms 0.0007% 73.31% 6287ms 4.41% KSP_PCApply (partial-inline) 4ms 0.0028% 73.31% 5579ms 3.91% KSPConvergedDefault 40ms 0.028% 73.34% 5258ms 3.69% AbstractCardiacTissue::UpdateCaches (inline) 1162ms 0.82% 74.15% 4978ms 3.49% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 3405ms 2.39% 76.54% 3411ms 2.39% __ieee754_log_fma 3206ms 2.25% 78.79% 3206ms 2.25% __memcpy_avx512_unaligned_erms 0 0% 78.79% 3177ms 2.23% PetscMemcpy (inline) 5ms 0.0035% 78.79% 3123ms 2.19% MatNullSpaceRemove 2116ms 1.48% 80.28% 2964ms 2.08% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 0 0% 80.28% 2888ms 2.03% boost::numeric::ublas::norm_inf (inline) 2483ms 1.74% 82.02% 2888ms 2.03% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 82.02% 2888ms 2.03% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 82.02% 2888ms 2.03% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 17ms 0.012% 82.03% 2788ms 1.96% VecCopy 2ms 0.0014% 82.03% 2766ms 1.94% VecCopy_Seq (partial-inline) 8ms 0.0056% 82.04% 2738ms 1.92% VecAXPY 2ms 0.0014% 82.04% 2730ms 1.92% VecAXPY_Seq 2716ms 1.91% 83.94% 2716ms 1.91% daxpy_ 18ms 0.013% 83.96% 2466ms 1.73% std::pow (inline) 191ms 0.13% 84.09% 2420ms 1.70% __pow 258ms 0.18% 84.27% 2410ms 1.69% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 2357ms 1.65% 85.92% 2357ms 1.65% [libchaste_heart.so] 281ms 0.2% 86.12% 2195ms 1.54% __ieee754_pow_fma 0 0% 86.12% 1969ms 1.38% VecMDot 15ms 0.011% 86.13% 1969ms 1.38% VecMXDot_Private 1949ms 1.37% 87.50% 1950ms 1.37% VecMDot_Seq 1807ms 1.27% 88.77% 1807ms 1.27% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 0 0% 88.77% 1713ms 1.20% AbstractFeVolumeIntegralAssembler::DoAssemble 1712ms 1.20% 89.97% 1712ms 1.20% ddot_ 1266ms 0.89% 90.86% 1503ms 1.05% exp_inline (inline) 18ms 0.013% 90.87% 1435ms 1.01% AbstractFeVolumeIntegralAssembler::AssembleOnElement 0 0% 90.87% 1421ms 1% VecDot 0 0% 90.87% 1421ms 1% VecDot_Seq 1ms 0.0007% 90.87% 1421ms 1% VecXDot_Seq_Private.constprop.0 1245ms 0.87% 91.74% 1245ms 0.87% __memset_avx512_unaligned_erms 0 0% 91.74% 1233ms 0.86% PetscMemzero (inline) 0 0% 91.74% 1180ms 0.83% AbstractFeAssemblerInterface::AssembleMatrix (inline) 5ms 0.0035% 91.75% 1149ms 0.81% VecMAXPY 1139ms 0.8% 92.55% 1144ms 0.8% VecMAXPY_Seq 413ms 0.29% 92.84% 977ms 0.69% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 92.84% 942ms 0.66% VecDuplicate 7ms 0.0049% 92.84% 923ms 0.65% boost::numeric::ublas::matrix_assign (inline) 123ms 0.086% 92.93% 913ms 0.64% boost::numeric::ublas::indexing_matrix_assign 0 0% 92.93% 891ms 0.63% CuboidMeshConstructor::Construct 0 0% 92.93% 890ms 0.62% AbstractTetrahedralMesh::ConstructRegularSlabMesh 2ms 0.0014% 92.93% 890ms 0.62% DistributedTetrahedralMesh::ConstructCuboid 8ms 0.0056% 92.93% 815ms 0.57% PetscMallocA 1ms 0.0007% 92.93% 809ms 0.57% Element::Element 9ms 0.0063% 92.94% 807ms 0.57% PetscMallocAlign (partial-inline) 2ms 0.0014% 92.94% 805ms 0.56% AbstractTetrahedralElement::CalculateJacobian 0 0% 92.94% 759ms 0.53% AbstractTetrahedralElement::AbstractTetrahedralElement 6ms 0.0042% 92.95% 754ms 0.53% VecScale 6ms 0.0042% 92.95% 748ms 0.52% VecScale_Seq 3ms 0.0021% 92.95% 746ms 0.52% BidomainAssembler::ComputeMatrixTerm 738ms 0.52% 93.47% 738ms 0.52% dscal_