File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3553ms, 86.93% of 4087ms total Dropped 482 nodes (cum <= 20.43ms) flat flat% sum% cum cum% 0 0% 0% 4078ms 99.78% __libc_start_call_main 0 0% 0% 4078ms 99.78% __libc_start_main_impl 0 0% 0% 4078ms 99.78% _start 0 0% 0% 4075ms 99.71% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4075ms 99.71% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4075ms 99.71% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4075ms 99.71% main 0 0% 0% 4037ms 98.78% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4037ms 98.78% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4037ms 98.78% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 4029ms 98.58% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4013ms 98.19% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.024% 2043ms 49.99% LinearSystem::Solve 0 0% 0.024% 2007ms 49.11% KSPSolve 7ms 0.17% 0.2% 2007ms 49.11% KSPSolve_Private 2ms 0.049% 0.24% 1966ms 48.10% KSPSolve_CG 12ms 0.29% 0.54% 1669ms 40.84% AbstractCardiacTissue::SolveCellSystems 3ms 0.073% 0.61% 1314ms 32.15% PCApply 0 0% 0.61% 1306ms 31.95% PCApply_BJacobi_Singleblock 69ms 1.69% 2.30% 1105ms 27.04% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.30% 1101ms 26.94% KSPSolve_PREONLY (partial-inline) 0 0% 2.30% 1043ms 25.52% KSP_PCApply.lto_priv.7 5ms 0.12% 2.42% 1043ms 25.52% MatSolve 0 0% 2.42% 1043ms 25.52% PCApply_ICC 1028ms 25.15% 27.58% 1032ms 25.25% MatSolve_SeqSBAIJ_1_NaturalOrdering 219ms 5.36% 32.93% 956ms 23.39% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 11ms 0.27% 33.20% 520ms 12.72% AbstractCardiacTissue::UpdateCaches (inline) 7ms 0.17% 33.37% 519ms 12.70% MatMult 7ms 0.17% 33.55% 500ms 12.23% MatMult_SeqAIJ (partial-inline) 489ms 11.96% 45.51% 499ms 12.21% MatMult_SeqAIJ_Inode (inline) 144ms 3.52% 49.03% 488ms 11.94% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 50ms 1.22% 50.26% 420ms 10.28% CardiacNewtonSolver::Solve 1ms 0.024% 50.28% 373ms 9.13% KSPConvergedDefault 0 0% 50.28% 370ms 9.05% KSP_PCApply (partial-inline) 57ms 1.39% 51.68% 264ms 6.46% BidomainSolver::SetupLinearSystem 131ms 3.21% 54.88% 250ms 6.12% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 19ms 0.46% 55.35% 223ms 5.46% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 30ms 0.73% 56.08% 202ms 4.94% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 56.08% 201ms 4.92% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 35ms 0.86% 56.94% 201ms 4.92% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 1ms 0.024% 56.96% 164ms 4.01% std::pow (inline) 163ms 3.99% 60.95% 163ms 3.99% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 14ms 0.34% 61.29% 163ms 3.99% __pow 1ms 0.024% 61.32% 159ms 3.89% MatNullSpaceRemove 26ms 0.64% 61.95% 147ms 3.60% __ieee754_pow_fma 61ms 1.49% 63.45% 134ms 3.28% __GI___exp 119ms 2.91% 66.36% 120ms 2.94% __ieee754_log_fma 0 0% 66.36% 107ms 2.62% boost::numeric::ublas::norm_inf (inline) 81ms 1.98% 68.34% 107ms 2.62% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 68.34% 107ms 2.62% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 68.34% 107ms 2.62% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 100ms 2.45% 70.79% 100ms 2.45% ddot_ 0 0% 70.79% 99ms 2.42% VecDuplicate 22ms 0.54% 71.32% 96ms 2.35% MPI_Wtime 0 0% 71.32% 92ms 2.25% VecMDot 2ms 0.049% 71.37% 92ms 2.25% VecMXDot_Private 85ms 2.08% 73.45% 90ms 2.20% exp_inline (inline) 17ms 0.42% 73.87% 78ms 1.91% PetscLogEventEndDefault 53ms 1.30% 75.17% 75ms 1.84% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 3ms 0.073% 75.24% 74ms 1.81% __GI___clock_gettime 69ms 1.69% 76.93% 72ms 1.76% __ieee754_exp_fma 71ms 1.74% 78.66% 71ms 1.74% [[vdso]] 1ms 0.024% 78.69% 69ms 1.69% VecTDot 1ms 0.024% 78.71% 68ms 1.66% VecNorm 7ms 0.17% 78.88% 67ms 1.64% PetscLogEventBeginDefault 3ms 0.073% 78.96% 66ms 1.61% KSP_RemoveNullSpace (inline) 4ms 0.098% 79.06% 66ms 1.61% VecMAXPY 64ms 1.57% 80.62% 66ms 1.61% VecMDot_Seq 2ms 0.049% 80.67% 60ms 1.47% VecDestroy 0 0% 80.67% 58ms 1.42% VecTDot_Seq 0 0% 80.67% 58ms 1.42% VecXDot_Seq_Private.constprop.0 2ms 0.049% 80.72% 54ms 1.32% VecDuplicate_Seq 2ms 0.049% 80.77% 53ms 1.30% VecNorm_Seq 12ms 0.29% 81.06% 46ms 1.13% PetscMallocA 1ms 0.024% 81.09% 45ms 1.10% VecDuplicate_MPI 45ms 1.10% 82.19% 45ms 1.10% [libchaste_heart.so] 41ms 1.00% 83.19% 41ms 1.00% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 4ms 0.098% 83.29% 40ms 0.98% VecCreate 3ms 0.073% 83.36% 35ms 0.86% VecCopy 32ms 0.78% 84.14% 35ms 0.86% VecMAXPY_Seq 0 0% 84.14% 34ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 84.14% 34ms 0.83% PetscInitialize 1ms 0.024% 84.17% 34ms 0.83% PetscMallocAlign 0 0% 84.17% 34ms 0.83% PetscSetup::setUpWorld 0 0% 84.17% 34ms 0.83% PetscSetupUtils::CommonSetup 0 0% 84.17% 34ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 84.17% 33ms 0.81% PMPI_Init_thread 0 0% 84.17% 33ms 0.81% ompi_mpi_init 0 0% 84.17% 31ms 0.76% AbstractBidomainSolver::FinaliseLinearSystem 12ms 0.29% 84.46% 30ms 0.73% AbstractBidomainSolver::CheckCompatibilityCondition 30ms 0.73% 85.20% 30ms 0.73% log_inline (inline) 3ms 0.073% 85.27% 27ms 0.66% VecSetType 1ms 0.024% 85.29% 26ms 0.64% 3ms 0.073% 85.37% 25ms 0.61% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 1ms 0.024% 85.39% 25ms 0.61% VecCreate_MPI_Private 0 0% 85.39% 25ms 0.61% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 85.39% 25ms 0.61% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 85.39% 25ms 0.61% boost_numeric_ublas_abs (inline) 0 0% 85.39% 25ms 0.61% orte_init 25ms 0.61% 86.00% 25ms 0.61% std::abs (inline) 2ms 0.049% 86.05% 23ms 0.56% PetscHeaderDestroy_Private 0 0% 86.05% 23ms 0.56% VecNorm_MPI 1ms 0.024% 86.08% 23ms 0.56% VecNorm_MPI_Default.constprop.0 4ms 0.098% 86.18% 23ms 0.56% VecSet 4ms 0.098% 86.27% 23ms 0.56% __GI___libc_malloc 22ms 0.54% 86.81% 22ms 0.54% DistributedVector::Iterator::operator!= 4ms 0.098% 86.91% 21ms 0.51% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 1ms 0.024% 86.93% 21ms 0.51% __posix_memalign