File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3558ms, 86.49% of 4114ms total Dropped 484 nodes (cum <= 20.57ms) flat flat% sum% cum cum% 0 0% 0% 4106ms 99.81% __libc_start_call_main 0 0% 0% 4106ms 99.81% __libc_start_main_impl 0 0% 0% 4106ms 99.81% _start 0 0% 0% 4103ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4103ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4103ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4103ms 99.73% main 0 0% 0% 4065ms 98.81% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4065ms 98.81% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4065ms 98.81% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 4058ms 98.64% AbstractCardiacProblem::Solve 0 0% 0% 4039ms 98.18% AbstractDynamicLinearPdeSolver::Solve 0 0% 0% 2096ms 50.95% LinearSystem::Solve 0 0% 0% 2045ms 49.71% KSPSolve 12ms 0.29% 0.29% 2045ms 49.71% KSPSolve_Private 0 0% 0.29% 1998ms 48.57% KSPSolve_CG 7ms 0.17% 0.46% 1679ms 40.81% AbstractCardiacTissue::SolveCellSystems 4ms 0.097% 0.56% 1308ms 31.79% PCApply 1ms 0.024% 0.58% 1294ms 31.45% PCApply_BJacobi_Singleblock 53ms 1.29% 1.87% 1111ms 27.01% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 1.87% 1067ms 25.94% KSPSolve_PREONLY (partial-inline) 0 0% 1.87% 1025ms 24.91% KSP_PCApply.lto_priv.7 1ms 0.024% 1.90% 1015ms 24.67% PCApply_ICC 0 0% 1.90% 1014ms 24.65% MatSolve 1003ms 24.38% 26.28% 1010ms 24.55% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 207ms 5.03% 31.31% 991ms 24.09% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 19ms 0.46% 31.77% 531ms 12.91% AbstractCardiacTissue::UpdateCaches (inline) 133ms 3.23% 35.00% 496ms 12.06% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 2ms 0.049% 35.05% 489ms 11.89% MatMult 4ms 0.097% 35.15% 479ms 11.64% MatMult_SeqAIJ 470ms 11.42% 46.57% 478ms 11.62% MatMult_SeqAIJ_Inode (inline) 51ms 1.24% 47.81% 426ms 10.35% CardiacNewtonSolver::Solve 2ms 0.049% 47.86% 384ms 9.33% KSPConvergedDefault 0 0% 47.86% 367ms 8.92% KSP_PCApply (partial-inline) 142ms 3.45% 51.31% 265ms 6.44% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 19ms 0.46% 51.77% 235ms 5.71% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 46ms 1.12% 52.89% 229ms 5.57% BidomainSolver::SetupLinearSystem 32ms 0.78% 53.67% 225ms 5.47% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 53.67% 213ms 5.18% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 42ms 1.02% 54.69% 213ms 5.18% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 185ms 4.50% 59.19% 185ms 4.50% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.024% 59.21% 161ms 3.91% std::pow (inline) 21ms 0.51% 59.72% 160ms 3.89% __pow 0 0% 59.72% 151ms 3.67% VecDuplicate 0 0% 59.72% 148ms 3.60% MatNullSpaceRemove 147ms 3.57% 63.30% 148ms 3.60% __ieee754_log_fma 142ms 3.45% 66.75% 142ms 3.45% ddot_ 23ms 0.56% 67.31% 138ms 3.35% __ieee754_pow_fma 77ms 1.87% 69.18% 135ms 3.28% __GI___exp 3ms 0.073% 69.25% 99ms 2.41% VecTDot 0 0% 69.25% 96ms 2.33% boost::numeric::ublas::norm_inf (inline) 74ms 1.80% 71.05% 96ms 2.33% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.05% 96ms 2.33% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.05% 96ms 2.33% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 1ms 0.024% 71.07% 93ms 2.26% VecMDot 7ms 0.17% 71.24% 92ms 2.24% VecMXDot_Private 1ms 0.024% 71.27% 86ms 2.09% VecNorm 0 0% 71.27% 84ms 2.04% VecDuplicate_Seq 0 0% 71.27% 81ms 1.97% VecTDot_Seq 0 0% 71.27% 81ms 1.97% VecXDot_Seq_Private.constprop.0 77ms 1.87% 73.14% 80ms 1.94% exp_inline (inline) 2ms 0.049% 73.19% 76ms 1.85% VecNorm_Seq 20ms 0.49% 73.68% 75ms 1.82% MPI_Wtime 2ms 0.049% 73.72% 67ms 1.63% VecCreate 3ms 0.073% 73.80% 67ms 1.63% VecDuplicate_MPI 8ms 0.19% 73.99% 66ms 1.60% PetscMallocA 32ms 0.78% 74.77% 65ms 1.58% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 64ms 1.56% 76.32% 64ms 1.56% VecMDot_Seq 11ms 0.27% 76.59% 59ms 1.43% PetscLogEventBeginDefault 11ms 0.27% 76.86% 59ms 1.43% PetscLogEventEndDefault 4ms 0.097% 76.96% 58ms 1.41% PetscMallocAlign (partial-inline) 55ms 1.34% 78.29% 55ms 1.34% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 4ms 0.097% 78.39% 55ms 1.34% VecMAXPY 1ms 0.024% 78.42% 55ms 1.34% __GI___clock_gettime 51ms 1.24% 79.65% 55ms 1.34% __ieee754_exp_fma 54ms 1.31% 80.97% 54ms 1.31% [[vdso]] 2ms 0.049% 81.02% 47ms 1.14% VecDestroy 2ms 0.049% 81.06% 39ms 0.95% VecCreate_MPI_Private 6ms 0.15% 81.21% 39ms 0.95% VecSetType (partial-inline) 8ms 0.19% 81.40% 38ms 0.92% __GI___libc_malloc 0 0% 81.40% 37ms 0.9% VecNorm_MPI 1ms 0.024% 81.43% 37ms 0.9% VecNorm_MPI_Default.constprop.0 37ms 0.9% 82.33% 37ms 0.9% [libchaste_heart.so] 2ms 0.049% 82.38% 36ms 0.88% __posix_memalign 34ms 0.83% 83.20% 35ms 0.85% VecMAXPY_Seq 0 0% 83.20% 34ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 83.20% 34ms 0.83% PetscInitialize 0 0% 83.20% 34ms 0.83% PetscSetup::setUpWorld 0 0% 83.20% 34ms 0.83% PetscSetupUtils::CommonSetup 0 0% 83.20% 34ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 83.20% 33ms 0.8% PMPI_Init_thread 33ms 0.8% 84.01% 33ms 0.8% log_inline (inline) 0 0% 84.01% 33ms 0.8% ompi_mpi_init 14ms 0.34% 84.35% 31ms 0.75% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.35% 31ms 0.75% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 84.35% 29ms 0.7% PetscMemzero (inline) 4ms 0.097% 84.44% 29ms 0.7% VecCopy 1ms 0.024% 84.47% 28ms 0.68% 3ms 0.073% 84.54% 27ms 0.66% VecAYPX 3ms 0.073% 84.61% 26ms 0.63% PetscHeaderCreate_Private 0 0% 84.61% 26ms 0.63% VecCreate_Seq 12ms 0.29% 84.91% 25ms 0.61% _int_malloc 8ms 0.19% 85.10% 24ms 0.58% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 24ms 0.58% 85.68% 24ms 0.58% __memset_avx2_unaligned_erms 0 0% 85.68% 24ms 0.58% orte_init 6ms 0.15% 85.83% 23ms 0.56% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 1ms 0.024% 85.85% 23ms 0.56% PetscHeaderDestroy_Private 0 0% 85.85% 23ms 0.56% PetscMemcpy (inline) 3ms 0.073% 85.93% 23ms 0.56% VecStashCreate_Private 2ms 0.049% 85.97% 22ms 0.53% VecAXPY 0 0% 85.97% 21ms 0.51% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 85.97% 21ms 0.51% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 85.97% 21ms 0.51% boost_numeric_ublas_abs (inline) 21ms 0.51% 86.49% 21ms 0.51% std::abs (inline)