File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3522ms, 87.01% of 4048ms total Dropped 471 nodes (cum <= 20.24ms) flat flat% sum% cum cum% 0 0% 0% 4041ms 99.83% __libc_start_call_main 0 0% 0% 4041ms 99.83% __libc_start_main_impl 0 0% 0% 4041ms 99.83% _start 0 0% 0% 4038ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4038ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4038ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4038ms 99.75% main 0 0% 0% 4001ms 98.84% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4001ms 98.84% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4001ms 98.84% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.025% 0.025% 3993ms 98.64% AbstractCardiacProblem::Solve 1ms 0.025% 0.049% 3982ms 98.37% AbstractDynamicLinearPdeSolver::Solve 1ms 0.025% 0.074% 1965ms 48.54% LinearSystem::Solve 0 0% 0.074% 1907ms 47.11% KSPSolve 7ms 0.17% 0.25% 1907ms 47.11% KSPSolve_Private 3ms 0.074% 0.32% 1867ms 46.12% KSPSolve_CG 8ms 0.2% 0.52% 1669ms 41.23% AbstractCardiacTissue::SolveCellSystems 3ms 0.074% 0.59% 1186ms 29.30% PCApply 0 0% 0.59% 1176ms 29.05% PCApply_BJacobi_Singleblock 72ms 1.78% 2.37% 1128ms 27.87% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 213ms 5.26% 7.63% 992ms 24.51% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 0 0% 7.63% 990ms 24.46% KSPSolve_PREONLY (partial-inline) 1ms 0.025% 7.66% 957ms 23.64% KSP_PCApply.lto_priv.7 1ms 0.025% 7.68% 932ms 23.02% PCApply_ICC 1ms 0.025% 7.71% 931ms 23.00% MatSolve 916ms 22.63% 30.34% 922ms 22.78% MatSolve_SeqSBAIJ_1_NaturalOrdering 3ms 0.074% 30.41% 545ms 13.46% MatMult 11ms 0.27% 30.68% 534ms 13.19% MatMult_SeqAIJ (partial-inline) 519ms 12.82% 43.50% 533ms 13.17% MatMult_SeqAIJ_Inode (inline) 12ms 0.3% 43.80% 491ms 12.13% AbstractCardiacTissue::UpdateCaches (inline) 128ms 3.16% 46.96% 463ms 11.44% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 58ms 1.43% 48.39% 440ms 10.87% CardiacNewtonSolver::Solve 1ms 0.025% 48.42% 330ms 8.15% KSPConvergedDefault 0 0% 48.42% 330ms 8.15% KSP_PCApply (partial-inline) 53ms 1.31% 49.73% 298ms 7.36% BidomainSolver::SetupLinearSystem 133ms 3.29% 53.01% 263ms 6.50% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 29ms 0.72% 53.73% 230ms 5.68% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 39ms 0.96% 54.69% 216ms 5.34% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 54.69% 200ms 4.94% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 39ms 0.96% 55.66% 200ms 4.94% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 169ms 4.17% 59.83% 169ms 4.17% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 59.86% 168ms 4.15% std::pow (inline) 30ms 0.74% 60.60% 167ms 4.13% __pow 2ms 0.049% 60.65% 140ms 3.46% MatNullSpaceRemove 17ms 0.42% 61.07% 136ms 3.36% __ieee754_pow_fma 133ms 3.29% 64.35% 133ms 3.29% ddot_ 128ms 3.16% 67.51% 131ms 3.24% __ieee754_log_fma 66ms 1.63% 69.15% 130ms 3.21% __GI___exp 0 0% 69.15% 122ms 3.01% VecDuplicate 0 0% 69.15% 107ms 2.64% boost::numeric::ublas::norm_inf (inline) 74ms 1.83% 70.97% 107ms 2.64% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 70.97% 107ms 2.64% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 70.97% 107ms 2.64% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 21ms 0.52% 71.49% 100ms 2.47% MPI_Wtime 0 0% 71.49% 91ms 2.25% VecMDot 5ms 0.12% 71.62% 91ms 2.25% VecMXDot_Private 3ms 0.074% 71.69% 87ms 2.15% VecTDot 1ms 0.025% 71.71% 80ms 1.98% VecNorm 2ms 0.049% 71.76% 79ms 1.95% __GI___clock_gettime 77ms 1.90% 73.67% 79ms 1.95% exp_inline (inline) 77ms 1.90% 75.57% 77ms 1.90% [[vdso]] 9ms 0.22% 75.79% 74ms 1.83% PetscLogEventEndDefault 0 0% 75.79% 71ms 1.75% VecNorm_Seq 0 0% 75.79% 71ms 1.75% VecTDot_Seq 0 0% 75.79% 71ms 1.75% VecXDot_Seq_Private.constprop.0 1ms 0.025% 75.82% 66ms 1.63% VecDuplicate_MPI 38ms 0.94% 76.75% 63ms 1.56% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 3ms 0.074% 76.83% 63ms 1.56% VecCreate 58ms 1.43% 78.26% 63ms 1.56% __ieee754_exp_fma 10ms 0.25% 78.51% 59ms 1.46% PetscLogEventBeginDefault 2ms 0.049% 78.56% 59ms 1.46% VecDestroy 2ms 0.049% 78.61% 58ms 1.43% KSP_RemoveNullSpace (inline) 1ms 0.025% 78.63% 56ms 1.38% VecDuplicate_Seq 6ms 0.15% 78.78% 54ms 1.33% PetscMallocA 51ms 1.26% 80.04% 52ms 1.28% VecMDot_Seq 2ms 0.049% 80.09% 48ms 1.19% PetscMallocAlign 4ms 0.099% 80.19% 47ms 1.16% VecMAXPY 0 0% 80.19% 39ms 0.96% AbstractBidomainSolver::FinaliseLinearSystem 11ms 0.27% 80.46% 38ms 0.94% AbstractBidomainSolver::CheckCompatibilityCondition 38ms 0.94% 81.40% 38ms 0.94% log_inline (inline) 37ms 0.91% 82.31% 37ms 0.91% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 35ms 0.86% 83.18% 35ms 0.86% [libchaste_heart.so] 5ms 0.12% 83.30% 34ms 0.84% VecCreate_MPI_Private 0 0% 83.30% 33ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 83.30% 33ms 0.82% PetscInitialize 0 0% 83.30% 33ms 0.82% PetscSetup::setUpWorld 0 0% 83.30% 33ms 0.82% PetscSetupUtils::CommonSetup 0 0% 83.30% 33ms 0.82% PetscSetupUtils::InitialisePetsc 0 0% 83.30% 32ms 0.79% PMPI_Init_thread 2ms 0.049% 83.35% 32ms 0.79% PetscHeaderDestroy_Private 0 0% 83.35% 32ms 0.79% ompi_mpi_init 9ms 0.22% 83.57% 31ms 0.77% __GI___libc_malloc 3ms 0.074% 83.65% 30ms 0.74% __posix_memalign 0 0% 83.65% 29ms 0.72% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 83.65% 29ms 0.72% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 83.65% 29ms 0.72% boost_numeric_ublas_abs (inline) 29ms 0.72% 84.36% 29ms 0.72% std::abs (inline) 2ms 0.049% 84.41% 28ms 0.69% PetscHeaderCreate_Private 2ms 0.049% 84.46% 27ms 0.67% 24ms 0.59% 85.05% 24ms 0.59% DistributedVector::Iterator::operator!= 1ms 0.025% 85.08% 24ms 0.59% VecAXPY 1ms 0.025% 85.10% 24ms 0.59% VecCopy 23ms 0.57% 85.67% 24ms 0.59% VecMAXPY_Seq 6ms 0.15% 85.82% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 5ms 0.12% 85.94% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 12ms 0.3% 86.24% 23ms 0.57% _int_malloc 8ms 0.2% 86.44% 22ms 0.54% AbstractCardiacCell::SetVoltage 22ms 0.54% 86.98% 22ms 0.54% DistributedVector::Stripe::operator[] (inline) 0 0% 86.98% 22ms 0.54% PetscMemcpy (inline) 0 0% 86.98% 22ms 0.54% VecNorm_MPI 0 0% 86.98% 22ms 0.54% VecNorm_MPI_Default.constprop.0 0 0% 86.98% 22ms 0.54% orte_init 1ms 0.025% 87.01% 21ms 0.52% VecSetType