File: TestConvergenceEfficiency Type: cpu Showing nodes accounting for 156469ms, 93.82% of 166769ms total Dropped 1120 nodes (cum <= 833.85ms) flat flat% sum% cum cum% 0 0% 0% 166737ms 100% __libc_start_call_main 0 0% 0% 166737ms 100% __libc_start_main_impl 0 0% 0% 166737ms 100% _start 0 0% 0% 166734ms 100% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 166734ms 100% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 166734ms 100% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 166734ms 100% main 0 0% 0% 166703ms 100% AbstractConvergenceTester::Converge 0 0% 0% 166703ms 100% CxxTest::RealTestDescription::run 0 0% 0% 166703ms 100% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 166703ms 100% CxxTest::TestRunner::runTest (inline) 0 0% 0% 166703ms 100% TestConvergenceEfficiency::Test3DSpace10Efficiency (inline) 0 0% 0% 166703ms 100% TestDescription_TestConvergenceEfficiency_Test3DSpace10Efficiency::runTest (inline) 5ms 0.003% 0.003% 165511ms 99.25% AbstractCardiacProblem::Solve 10ms 0.006% 0.009% 164782ms 98.81% AbstractDynamicLinearPdeSolver::Solve 4ms 0.0024% 0.011% 115409ms 69.20% LinearSystem::Solve 0 0% 0.011% 114887ms 68.89% KSPSolve 8ms 0.0048% 0.016% 114887ms 68.89% KSPSolve_Private 24ms 0.014% 0.031% 114398ms 68.60% KSPSolve_SYMMLQ 5ms 0.003% 0.034% 74512ms 44.68% MatMult 38ms 0.023% 0.056% 74507ms 44.68% MatMult_SeqAIJ 73436ms 44.03% 44.09% 74489ms 44.67% MatMult_SeqAIJ_Inode (inline) 5ms 0.003% 44.09% 39937ms 23.95% PCApply 2ms 0.0012% 44.10% 39928ms 23.94% PCApply_BJacobi_Singleblock 7ms 0.0042% 44.10% 36813ms 22.07% KSPSolve_PREONLY (partial-inline) 2ms 0.0012% 44.10% 35398ms 21.23% PCApply_ICC 3ms 0.0018% 44.10% 35396ms 21.22% MatSolve 34910ms 20.93% 65.04% 35393ms 21.22% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 1ms 0.0006% 65.04% 34462ms 20.66% KSP_PCApply.lto_priv.18 222ms 0.13% 65.17% 32894ms 19.72% AbstractCardiacTissue::SolveCellSystems 532ms 0.32% 65.49% 26055ms 15.62% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 3721ms 2.23% 67.72% 21994ms 13.19% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 870ms 0.52% 68.24% 15349ms 9.20% BidomainSolver::SetupLinearSystem 1240ms 0.74% 68.98% 14865ms 8.91% CardiacNewtonSolver::Solve 5580ms 3.35% 72.33% 9890ms 5.93% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 1ms 0.0006% 72.33% 7715ms 4.63% KSP_PCApply (partial-inline) 4ms 0.0024% 72.33% 6916ms 4.15% KSPConvergedDefault 49ms 0.029% 72.36% 5940ms 3.56% AbstractCardiacTissue::UpdateCaches (inline) 1236ms 0.74% 73.10% 5605ms 3.36% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 4202ms 2.52% 75.62% 4202ms 2.52% __memcpy_evex_unaligned_erms 0 0% 75.62% 4164ms 2.50% PetscMemcpy (inline) 9ms 0.0054% 75.63% 3891ms 2.33% MatNullSpaceRemove 3889ms 2.33% 77.96% 3891ms 2.33% __ieee754_log_fma 23ms 0.014% 77.97% 3719ms 2.23% VecCopy 2ms 0.0012% 77.98% 3692ms 2.21% VecCopy_Seq (partial-inline) 8ms 0.0048% 77.98% 3598ms 2.16% VecAXPY 1ms 0.0006% 77.98% 3590ms 2.15% VecAXPY_Seq 3576ms 2.14% 80.13% 3576ms 2.14% daxpy_ 2497ms 1.50% 81.62% 3452ms 2.07% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 0 0% 81.62% 3316ms 1.99% boost::numeric::ublas::norm_inf (inline) 2817ms 1.69% 83.31% 3316ms 1.99% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 83.31% 3316ms 1.99% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 83.31% 3316ms 1.99% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 14ms 0.0084% 83.32% 2813ms 1.69% std::pow (inline) 312ms 0.19% 83.51% 2800ms 1.68% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 217ms 0.13% 83.64% 2767ms 1.66% __pow 2700ms 1.62% 85.26% 2700ms 1.62% [libchaste_heart.so] 334ms 0.2% 85.46% 2505ms 1.50% __ieee754_pow_fma 0 0% 85.46% 2497ms 1.50% VecMDot 14ms 0.0084% 85.47% 2497ms 1.50% VecMXDot_Private 2464ms 1.48% 86.94% 2474ms 1.48% VecMDot_Seq 2138ms 1.28% 88.23% 2138ms 1.28% ddot_ 2083ms 1.25% 89.47% 2083ms 1.25% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 3ms 0.0018% 89.48% 1955ms 1.17% AbstractFeVolumeIntegralAssembler::DoAssemble 4ms 0.0024% 89.48% 1782ms 1.07% VecDot 1ms 0.0006% 89.48% 1778ms 1.07% VecDot_Seq 3ms 0.0018% 89.48% 1777ms 1.07% VecXDot_Seq_Private.constprop.0 1537ms 0.92% 90.40% 1761ms 1.06% exp_inline (inline) 27ms 0.016% 90.42% 1665ms 1% AbstractFeVolumeIntegralAssembler::AssembleOnElement 1642ms 0.98% 91.40% 1642ms 0.98% __memset_evex_unaligned_erms 0 0% 91.40% 1633ms 0.98% PetscMemzero (inline) 10ms 0.006% 91.41% 1385ms 0.83% VecMAXPY 1372ms 0.82% 92.23% 1375ms 0.82% VecMAXPY_Seq 0 0% 92.23% 1353ms 0.81% AbstractFeAssemblerInterface::AssembleMatrix (inline) 3ms 0.0018% 92.23% 1345ms 0.81% VecDuplicate 11ms 0.0066% 92.24% 1150ms 0.69% PetscMallocA 447ms 0.27% 92.51% 1146ms 0.69% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 10ms 0.006% 92.51% 1139ms 0.68% PetscMallocAlign (partial-inline) 3ms 0.0018% 92.52% 1112ms 0.67% boost::numeric::ublas::matrix_assign (inline) 173ms 0.1% 92.62% 1107ms 0.66% boost::numeric::ublas::indexing_matrix_assign 0 0% 92.62% 1082ms 0.65% KSP_RemoveNullSpace (inline) 1027ms 0.62% 93.24% 1027ms 0.62% _mm_prefetch (inline) 0 0% 93.24% 1019ms 0.61% CuboidMeshConstructor::Construct 0 0% 93.24% 1018ms 0.61% AbstractTetrahedralMesh::ConstructRegularSlabMesh 0 0% 93.24% 1018ms 0.61% DistributedTetrahedralMesh::ConstructCuboid 5ms 0.003% 93.24% 975ms 0.58% VecScale 6ms 0.0036% 93.24% 970ms 0.58% VecScale_Seq 956ms 0.57% 93.82% 956ms 0.57% dscal_ 3ms 0.0018% 93.82% 903ms 0.54% AbstractTetrahedralElement::CalculateJacobian 1ms 0.0006% 93.82% 902ms 0.54% Element::Element 5ms 0.003% 93.82% 880ms 0.53% BidomainAssembler::ComputeMatrixTerm 5ms 0.003% 93.82% 863ms 0.52% boost::numeric::ublas::c_matrix::c_matrix (inline) 0 0% 93.82% 847ms 0.51% AbstractTetrahedralElement::AbstractTetrahedralElement