File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3519ms, 86.31% of 4077ms total Dropped 491 nodes (cum <= 20.39ms) flat flat% sum% cum cum% 0 0% 0% 4070ms 99.83% __libc_start_call_main 0 0% 0% 4070ms 99.83% __libc_start_main_impl 0 0% 0% 4070ms 99.83% _start 0 0% 0% 4067ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4067ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4067ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4067ms 99.75% main 0 0% 0% 4029ms 98.82% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4029ms 98.82% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4029ms 98.82% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 4021ms 98.63% AbstractCardiacProblem::Solve 0 0% 0% 4012ms 98.41% AbstractDynamicLinearPdeSolver::Solve 1ms 0.025% 0.025% 2039ms 50.01% LinearSystem::Solve 0 0% 0.025% 1997ms 48.98% KSPSolve 4ms 0.098% 0.12% 1997ms 48.98% KSPSolve_Private 1ms 0.025% 0.15% 1948ms 47.78% KSPSolve_CG 15ms 0.37% 0.52% 1642ms 40.27% AbstractCardiacTissue::SolveCellSystems 1ms 0.025% 0.54% 1235ms 30.29% PCApply 0 0% 0.54% 1226ms 30.07% PCApply_BJacobi_Singleblock 61ms 1.50% 2.04% 1100ms 26.98% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 1ms 0.025% 2.06% 1037ms 25.44% KSPSolve_PREONLY (partial-inline) 4ms 0.098% 2.16% 993ms 24.36% MatSolve 0 0% 2.16% 993ms 24.36% PCApply_ICC 975ms 23.91% 26.07% 981ms 24.06% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 26.07% 971ms 23.82% KSP_PCApply.lto_priv.7 200ms 4.91% 30.98% 955ms 23.42% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 3ms 0.074% 31.05% 565ms 13.86% MatMult 9ms 0.22% 31.27% 547ms 13.42% MatMult_SeqAIJ (partial-inline) 532ms 13.05% 44.32% 547ms 13.42% MatMult_SeqAIJ_Inode (inline) 13ms 0.32% 44.64% 498ms 12.21% AbstractCardiacTissue::UpdateCaches (inline) 148ms 3.63% 48.27% 464ms 11.38% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 56ms 1.37% 49.64% 434ms 10.65% CardiacNewtonSolver::Solve 1ms 0.025% 49.67% 360ms 8.83% KSPConvergedDefault 0 0% 49.67% 339ms 8.31% KSP_PCApply (partial-inline) 63ms 1.55% 51.21% 292ms 7.16% BidomainSolver::SetupLinearSystem 138ms 3.38% 54.60% 257ms 6.30% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 17ms 0.42% 55.02% 217ms 5.32% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 28ms 0.69% 55.70% 203ms 4.98% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 55.70% 198ms 4.86% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 35ms 0.86% 56.56% 198ms 4.86% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 168ms 4.12% 60.68% 168ms 4.12% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 145ms 3.56% 64.24% 145ms 3.56% __ieee754_log_fma 1ms 0.025% 64.26% 144ms 3.53% std::pow (inline) 14ms 0.34% 64.61% 143ms 3.51% __pow 2ms 0.049% 64.66% 132ms 3.24% MatNullSpaceRemove 19ms 0.47% 65.12% 129ms 3.16% __ieee754_pow_fma 63ms 1.55% 66.67% 128ms 3.14% __GI___exp 115ms 2.82% 69.49% 115ms 2.82% ddot_ 1ms 0.025% 69.51% 112ms 2.75% VecDuplicate 0 0% 69.51% 111ms 2.72% boost::numeric::ublas::norm_inf (inline) 88ms 2.16% 71.67% 111ms 2.72% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.67% 111ms 2.72% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.67% 111ms 2.72% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 33ms 0.81% 72.48% 98ms 2.40% MPI_Wtime 3ms 0.074% 72.55% 89ms 2.18% VecTDot 2ms 0.049% 72.60% 81ms 1.99% VecNorm 43ms 1.05% 73.66% 79ms 1.94% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 14ms 0.34% 74.00% 79ms 1.94% PetscLogEventBeginDefault 0 0% 74.00% 78ms 1.91% VecMDot 7ms 0.17% 74.17% 78ms 1.91% VecMXDot_Private 0 0% 74.17% 69ms 1.69% VecTDot_Seq 0 0% 74.17% 69ms 1.69% VecXDot_Seq_Private.constprop.0 67ms 1.64% 75.82% 68ms 1.67% exp_inline (inline) 2ms 0.049% 75.86% 65ms 1.59% VecDuplicate_Seq 2ms 0.049% 75.91% 65ms 1.59% __GI___clock_gettime 14ms 0.34% 76.26% 63ms 1.55% PetscLogEventEndDefault 2ms 0.049% 76.31% 63ms 1.55% VecDestroy 63ms 1.55% 77.85% 63ms 1.55% [[vdso]] 53ms 1.30% 79.15% 63ms 1.55% __ieee754_exp_fma 1ms 0.025% 79.18% 62ms 1.52% VecNorm_Seq 5ms 0.12% 79.30% 53ms 1.30% PetscTimeSubtract (inline) 3ms 0.074% 79.37% 52ms 1.28% VecMAXPY 46ms 1.13% 80.50% 48ms 1.18% VecMDot_Seq 1ms 0.025% 80.52% 46ms 1.13% VecDuplicate_MPI 7ms 0.17% 80.70% 45ms 1.10% PetscMallocA 0 0% 80.70% 42ms 1.03% PetscTimeAdd (inline) 42ms 1.03% 81.73% 42ms 1.03% log_inline (inline) 3ms 0.074% 81.80% 40ms 0.98% VecSetType 3ms 0.074% 81.87% 38ms 0.93% PetscMallocAlign (partial-inline) 4ms 0.098% 81.97% 36ms 0.88% VecCreate 5ms 0.12% 82.09% 35ms 0.86% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.09% 35ms 0.86% AbstractBidomainSolver::FinaliseLinearSystem 34ms 0.83% 82.93% 34ms 0.83% [libchaste_heart.so] 0 0% 82.93% 33ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 82.93% 33ms 0.81% PMPI_Init_thread 0 0% 82.93% 33ms 0.81% PetscInitialize 0 0% 82.93% 33ms 0.81% PetscSetup::setUpWorld 0 0% 82.93% 33ms 0.81% PetscSetupUtils::CommonSetup 0 0% 82.93% 33ms 0.81% PetscSetupUtils::InitialisePetsc 3ms 0.074% 83.00% 33ms 0.81% VecCopy 1ms 0.025% 83.03% 33ms 0.81% VecCreate_Seq 0 0% 83.03% 33ms 0.81% ompi_mpi_init 32ms 0.78% 83.81% 32ms 0.78% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 5ms 0.12% 83.93% 30ms 0.74% VecAXPY 29ms 0.71% 84.65% 30ms 0.74% VecMAXPY_Seq 0 0% 84.65% 29ms 0.71% VecCreate_MPI_Private 0 0% 84.65% 29ms 0.71% VecNorm_MPI 1ms 0.025% 84.67% 29ms 0.71% VecNorm_MPI_Default.constprop.0 6ms 0.15% 84.82% 27ms 0.66% PetscHeaderDestroy_Private 1ms 0.025% 84.84% 25ms 0.61% 7ms 0.17% 85.01% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 3ms 0.074% 85.09% 23ms 0.56% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 4ms 0.098% 85.19% 23ms 0.56% PetscOptionsFindPair 2ms 0.049% 85.23% 23ms 0.56% __GI___libc_malloc 0 0% 85.23% 22ms 0.54% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 85.23% 22ms 0.54% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 85.23% 22ms 0.54% boost_numeric_ublas_abs (inline) 0 0% 85.23% 22ms 0.54% orte_init 22ms 0.54% 85.77% 22ms 0.54% std::abs (inline) 21ms 0.52% 86.29% 21ms 0.52% DistributedVector::Iterator::operator!= 0 0% 86.29% 21ms 0.52% PetscMemzero (inline) 1ms 0.025% 86.31% 21ms 0.52% VecAYPX 0 0% 86.31% 21ms 0.52% __posix_memalign