File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3505ms, 86.61% of 4047ms total Dropped 478 nodes (cum <= 20.23ms) flat flat% sum% cum cum% 0 0% 0% 4038ms 99.78% __libc_start_call_main 0 0% 0% 4038ms 99.78% __libc_start_main_impl 0 0% 0% 4038ms 99.78% _start 0 0% 0% 4035ms 99.70% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4035ms 99.70% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4035ms 99.70% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4035ms 99.70% main 0 0% 0% 3997ms 98.76% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3997ms 98.76% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3997ms 98.76% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 3989ms 98.57% AbstractCardiacProblem::Solve 2ms 0.049% 0.049% 3980ms 98.34% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.049% 1977ms 48.85% LinearSystem::Solve 0 0% 0.049% 1927ms 47.62% KSPSolve 7ms 0.17% 0.22% 1927ms 47.62% KSPSolve_Private 1ms 0.025% 0.25% 1876ms 46.36% KSPSolve_CG 9ms 0.22% 0.47% 1670ms 41.27% AbstractCardiacTissue::SolveCellSystems 2ms 0.049% 0.52% 1202ms 29.70% PCApply 1ms 0.025% 0.54% 1190ms 29.40% PCApply_BJacobi_Singleblock 57ms 1.41% 1.95% 1102ms 27.23% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 1.95% 1003ms 24.78% KSPSolve_PREONLY (partial-inline) 0 0% 1.95% 970ms 23.97% KSP_PCApply.lto_priv.7 212ms 5.24% 7.19% 957ms 23.65% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 3ms 0.074% 7.26% 947ms 23.40% MatSolve 0 0% 7.26% 947ms 23.40% PCApply_ICC 933ms 23.05% 30.32% 937ms 23.15% MatSolve_SeqSBAIJ_1_NaturalOrdering 20ms 0.49% 30.81% 522ms 12.90% AbstractCardiacTissue::UpdateCaches (inline) 2ms 0.049% 30.86% 514ms 12.70% MatMult 6ms 0.15% 31.01% 502ms 12.40% MatMult_SeqAIJ (partial-inline) 491ms 12.13% 43.14% 502ms 12.40% MatMult_SeqAIJ_Inode (inline) 126ms 3.11% 46.26% 485ms 11.98% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 65ms 1.61% 47.86% 434ms 10.72% CardiacNewtonSolver::Solve 1ms 0.025% 47.89% 338ms 8.35% KSP_PCApply (partial-inline) 2ms 0.049% 47.94% 337ms 8.33% KSPConvergedDefault 46ms 1.14% 49.07% 282ms 6.97% BidomainSolver::SetupLinearSystem 128ms 3.16% 52.24% 248ms 6.13% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 21ms 0.52% 52.76% 224ms 5.53% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 24ms 0.59% 53.35% 206ms 5.09% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 53.35% 199ms 4.92% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 40ms 0.99% 54.34% 199ms 4.92% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 24ms 0.59% 54.93% 176ms 4.35% __pow 0 0% 54.93% 176ms 4.35% std::pow (inline) 174ms 4.30% 59.23% 174ms 4.30% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 3ms 0.074% 59.30% 157ms 3.88% MatNullSpaceRemove 21ms 0.52% 59.82% 152ms 3.76% __ieee754_pow_fma 140ms 3.46% 63.28% 142ms 3.51% __ieee754_log_fma 74ms 1.83% 65.11% 136ms 3.36% __GI___exp 135ms 3.34% 68.45% 135ms 3.34% ddot_ 0 0% 68.45% 111ms 2.74% boost::numeric::ublas::norm_inf (inline) 81ms 2.00% 70.45% 111ms 2.74% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 70.45% 111ms 2.74% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 70.45% 111ms 2.74% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 0 0% 70.45% 105ms 2.59% VecMDot 7ms 0.17% 70.62% 105ms 2.59% VecMXDot_Private 0 0% 70.62% 102ms 2.52% VecDuplicate 24ms 0.59% 71.21% 98ms 2.42% MPI_Wtime 3ms 0.074% 71.29% 98ms 2.42% VecTDot 1ms 0.025% 71.31% 86ms 2.13% VecNorm 50ms 1.24% 72.55% 84ms 2.08% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 82ms 2.03% 74.57% 83ms 2.05% exp_inline (inline) 0 0% 74.57% 80ms 1.98% VecTDot_Seq 1ms 0.025% 74.60% 80ms 1.98% VecXDot_Seq_Private.constprop.0 1ms 0.025% 74.62% 74ms 1.83% __GI___clock_gettime 73ms 1.80% 76.43% 73ms 1.80% [[vdso]] 9ms 0.22% 76.65% 71ms 1.75% PetscLogEventBeginDefault 62ms 1.53% 78.18% 67ms 1.66% VecMDot_Seq 0 0% 78.18% 67ms 1.66% VecNorm_Seq 55ms 1.36% 79.54% 62ms 1.53% __ieee754_exp_fma 10ms 0.25% 79.79% 61ms 1.51% PetscLogEventEndDefault 1ms 0.025% 79.81% 53ms 1.31% VecDuplicate_Seq 2ms 0.049% 79.86% 49ms 1.21% VecDuplicate_MPI 4ms 0.099% 79.96% 49ms 1.21% VecMAXPY 46ms 1.14% 81.10% 46ms 1.14% log_inline (inline) 5ms 0.12% 81.22% 44ms 1.09% PetscMallocA 0 0% 81.22% 44ms 1.09% VecDestroy 44ms 1.09% 82.31% 44ms 1.09% [libchaste_heart.so] 3ms 0.074% 82.38% 43ms 1.06% VecCreate 0 0% 82.38% 42ms 1.04% AbstractBidomainSolver::FinaliseLinearSystem 11ms 0.27% 82.65% 41ms 1.01% AbstractBidomainSolver::CheckCompatibilityCondition 2ms 0.049% 82.70% 39ms 0.96% PetscMallocAlign 0 0% 82.70% 33ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 82.70% 33ms 0.82% PMPI_Init_thread 0 0% 82.70% 33ms 0.82% PetscInitialize 0 0% 82.70% 33ms 0.82% PetscSetup::setUpWorld 0 0% 82.70% 33ms 0.82% PetscSetupUtils::CommonSetup 0 0% 82.70% 33ms 0.82% PetscSetupUtils::InitialisePetsc 0 0% 82.70% 33ms 0.82% ompi_mpi_init 31ms 0.77% 83.47% 31ms 0.77% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 2ms 0.049% 83.52% 30ms 0.74% VecCreate_MPI_Private 2ms 0.049% 83.57% 29ms 0.72% 0 0% 83.57% 29ms 0.72% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 83.57% 29ms 0.72% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 83.57% 29ms 0.72% boost_numeric_ublas_abs (inline) 29ms 0.72% 84.28% 29ms 0.72% std::abs (inline) 26ms 0.64% 84.93% 28ms 0.69% VecMAXPY_Seq 27ms 0.67% 85.59% 27ms 0.67% DistributedVector::Iterator::operator!= 0 0% 85.59% 27ms 0.67% VecNorm_MPI 0 0% 85.59% 27ms 0.67% VecNorm_MPI_Default.constprop.0 5ms 0.12% 85.72% 26ms 0.64% VecCopy 4ms 0.099% 85.82% 26ms 0.64% __posix_memalign 3ms 0.074% 85.89% 24ms 0.59% PetscHeaderDestroy_Private 1ms 0.025% 85.92% 24ms 0.59% VecSetType 4ms 0.099% 86.01% 24ms 0.59% __GI___libc_malloc 4ms 0.099% 86.11% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 1ms 0.025% 86.14% 23ms 0.57% VecAYPX 0 0% 86.14% 23ms 0.57% orte_init 4ms 0.099% 86.24% 22ms 0.54% KSPConvergedReasonViewFromOptions 3ms 0.074% 86.31% 22ms 0.54% PetscOptionsGetViewer 6ms 0.15% 86.46% 22ms 0.54% VecSet 0 0% 86.46% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 6ms 0.15% 86.61% 21ms 0.52% PetscOptionsFindPair