File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3516ms, 86.73% of 4054ms total Dropped 472 nodes (cum <= 20.27ms) flat flat% sum% cum cum% 0 0% 0% 4046ms 99.80% __libc_start_call_main 0 0% 0% 4046ms 99.80% __libc_start_main_impl 0 0% 0% 4046ms 99.80% _start 0 0% 0% 4043ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4043ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4043ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4043ms 99.73% main 0 0% 0% 4007ms 98.84% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4007ms 98.84% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4007ms 98.84% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 3999ms 98.64% AbstractCardiacProblem::Solve 1ms 0.025% 0.025% 3980ms 98.17% AbstractDynamicLinearPdeSolver::Solve 1ms 0.025% 0.049% 1971ms 48.62% LinearSystem::Solve 0 0% 0.049% 1942ms 47.90% KSPSolve 7ms 0.17% 0.22% 1942ms 47.90% KSPSolve_Private 4ms 0.099% 0.32% 1901ms 46.89% KSPSolve_CG 12ms 0.3% 0.62% 1700ms 41.93% AbstractCardiacTissue::SolveCellSystems 3ms 0.074% 0.69% 1220ms 30.09% PCApply 1ms 0.025% 0.72% 1214ms 29.95% PCApply_BJacobi_Singleblock 61ms 1.50% 2.22% 1162ms 28.66% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 232ms 5.72% 7.94% 1029ms 25.38% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 0 0% 7.94% 1004ms 24.77% KSPSolve_PREONLY (partial-inline) 0 0% 7.94% 981ms 24.20% KSP_PCApply.lto_priv.7 1ms 0.025% 7.97% 949ms 23.41% PCApply_ICC 3ms 0.074% 8.04% 948ms 23.38% MatSolve 934ms 23.04% 31.08% 937ms 23.11% MatSolve_SeqSBAIJ_1_NaturalOrdering 7ms 0.17% 31.25% 546ms 13.47% MatMult 104ms 2.57% 33.82% 536ms 13.22% MatMult_SeqAIJ 8ms 0.2% 34.02% 492ms 12.14% AbstractCardiacTissue::UpdateCaches (inline) 138ms 3.40% 37.42% 468ms 11.54% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 68ms 1.68% 39.10% 462ms 11.40% CardiacNewtonSolver::Solve 429ms 10.58% 49.68% 436ms 10.75% MatMult_SeqAIJ_Inode (inline) 3ms 0.074% 49.75% 338ms 8.34% KSPConvergedDefault 0 0% 49.75% 329ms 8.12% KSP_PCApply (partial-inline) 59ms 1.46% 51.21% 274ms 6.76% BidomainSolver::SetupLinearSystem 138ms 3.40% 54.61% 258ms 6.36% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 35ms 0.86% 55.48% 237ms 5.85% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 31ms 0.76% 56.24% 229ms 5.65% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 56.24% 195ms 4.81% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 37ms 0.91% 57.15% 195ms 4.81% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 190ms 4.69% 61.84% 190ms 4.69% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 61.86% 150ms 3.70% MatNullSpaceRemove 22ms 0.54% 62.41% 146ms 3.60% __pow 0 0% 62.41% 146ms 3.60% std::pow (inline) 129ms 3.18% 65.59% 130ms 3.21% __ieee754_log_fma 71ms 1.75% 67.34% 128ms 3.16% __GI___exp 0 0% 67.34% 126ms 3.11% boost::numeric::ublas::norm_inf (inline) 78ms 1.92% 69.26% 126ms 3.11% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 69.26% 126ms 3.11% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 69.26% 126ms 3.11% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 16ms 0.39% 69.66% 123ms 3.03% __ieee754_pow_fma 122ms 3.01% 72.67% 122ms 3.01% ddot_ 1ms 0.025% 72.69% 105ms 2.59% VecDuplicate 0 0% 72.69% 92ms 2.27% VecMDot 5ms 0.12% 72.82% 92ms 2.27% VecMXDot_Private 3ms 0.074% 72.89% 92ms 2.27% VecTDot 20ms 0.49% 73.38% 84ms 2.07% MPI_Wtime 0 0% 73.38% 75ms 1.85% VecNorm 0 0% 73.38% 73ms 1.80% VecTDot_Seq 1ms 0.025% 73.41% 73ms 1.80% VecXDot_Seq_Private.constprop.0 35ms 0.86% 74.27% 71ms 1.75% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 0 0% 74.27% 69ms 1.70% VecDestroy 0 0% 74.27% 69ms 1.70% VecDuplicate_Seq 68ms 1.68% 75.95% 69ms 1.70% exp_inline (inline) 11ms 0.27% 76.22% 64ms 1.58% PetscLogEventBeginDefault 2ms 0.049% 76.27% 63ms 1.55% __GI___clock_gettime 0 0% 76.27% 62ms 1.53% VecNorm_Seq 61ms 1.50% 77.78% 61ms 1.50% [[vdso]] 56ms 1.38% 79.16% 59ms 1.46% VecMDot_Seq 4ms 0.099% 79.26% 57ms 1.41% VecMAXPY 52ms 1.28% 80.54% 57ms 1.41% __ieee754_exp_fma 8ms 0.2% 80.74% 52ms 1.28% PetscLogEventEndDefault 0 0% 80.74% 47ms 1.16% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 80.74% 47ms 1.16% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 80.74% 47ms 1.16% boost_numeric_ublas_abs (inline) 47ms 1.16% 81.89% 47ms 1.16% std::abs (inline) 4ms 0.099% 81.99% 45ms 1.11% VecCreate 3ms 0.074% 82.07% 40ms 0.99% PetscHeaderDestroy_Private 36ms 0.89% 82.96% 36ms 0.89% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 3ms 0.074% 83.03% 35ms 0.86% VecDuplicate_MPI 32ms 0.79% 83.82% 34ms 0.84% VecMAXPY_Seq 34ms 0.84% 84.66% 34ms 0.84% log_inline (inline) 0 0% 84.66% 32ms 0.79% CxxTest::RealWorldDescription::setUp 0 0% 84.66% 32ms 0.79% PMPI_Init_thread 0 0% 84.66% 32ms 0.79% PetscInitialize 0 0% 84.66% 32ms 0.79% PetscSetup::setUpWorld 0 0% 84.66% 32ms 0.79% PetscSetupUtils::CommonSetup 0 0% 84.66% 32ms 0.79% PetscSetupUtils::InitialisePetsc 0 0% 84.66% 32ms 0.79% ompi_mpi_init 5ms 0.12% 84.78% 31ms 0.76% PetscMallocA 2ms 0.049% 84.83% 31ms 0.76% VecSetType 4ms 0.099% 84.93% 30ms 0.74% 3ms 0.074% 85.00% 29ms 0.72% VecCopy 5ms 0.12% 85.13% 28ms 0.69% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 85.13% 28ms 0.69% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 85.13% 28ms 0.69% VecNorm_MPI 1ms 0.025% 85.15% 28ms 0.69% VecNorm_MPI_Default.constprop.0 8ms 0.2% 85.35% 27ms 0.67% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 4ms 0.099% 85.45% 26ms 0.64% PetscHeaderCreate_Private 0 0% 85.45% 26ms 0.64% PetscMallocAlign 6ms 0.15% 85.59% 25ms 0.62% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 23ms 0.57% 86.16% 23ms 0.57% [libchaste_heart.so] 0 0% 86.16% 23ms 0.57% orte_init 22ms 0.54% 86.70% 22ms 0.54% DistributedVector::Iterator::operator!= 0 0% 86.70% 22ms 0.54% PetscMemcpy (inline) 1ms 0.025% 86.73% 21ms 0.52% VecCreate_MPI_Private 0 0% 86.73% 21ms 0.52% VecCreate_Seq