File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3552ms, 87.75% of 4048ms total Dropped 469 nodes (cum <= 20.24ms) flat flat% sum% cum cum% 0 0% 0% 4040ms 99.80% __libc_start_call_main 0 0% 0% 4040ms 99.80% __libc_start_main_impl 0 0% 0% 4040ms 99.80% _start 0 0% 0% 4037ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4037ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4037ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4037ms 99.73% main 0 0% 0% 4002ms 98.86% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4002ms 98.86% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4002ms 98.86% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 3993ms 98.64% AbstractCardiacProblem::Solve 0 0% 0% 3977ms 98.25% AbstractDynamicLinearPdeSolver::Solve 2ms 0.049% 0.049% 1984ms 49.01% LinearSystem::Solve 0 0% 0.049% 1956ms 48.32% KSPSolve 9ms 0.22% 0.27% 1956ms 48.32% KSPSolve_Private 2ms 0.049% 0.32% 1928ms 47.63% KSPSolve_CG 13ms 0.32% 0.64% 1696ms 41.90% AbstractCardiacTissue::SolveCellSystems 5ms 0.12% 0.77% 1217ms 30.06% PCApply 0 0% 0.77% 1203ms 29.72% PCApply_BJacobi_Singleblock 73ms 1.80% 2.57% 1098ms 27.12% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.57% 995ms 24.58% KSPSolve_PREONLY (partial-inline) 0 0% 2.57% 972ms 24.01% KSP_PCApply.lto_priv.7 184ms 4.55% 7.11% 951ms 23.49% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 8ms 0.2% 7.31% 935ms 23.10% MatSolve 0 0% 7.31% 935ms 23.10% PCApply_ICC 912ms 22.53% 29.84% 919ms 22.70% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 17ms 0.42% 30.26% 547ms 13.51% AbstractCardiacTissue::UpdateCaches (inline) 3ms 0.074% 30.34% 545ms 13.46% MatMult 5ms 0.12% 30.46% 537ms 13.27% MatMult_SeqAIJ (partial-inline) 525ms 12.97% 43.43% 537ms 13.27% MatMult_SeqAIJ_Inode (inline) 143ms 3.53% 46.96% 504ms 12.45% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 64ms 1.58% 48.54% 446ms 11.02% CardiacNewtonSolver::Solve 4ms 0.099% 48.64% 365ms 9.02% KSPConvergedDefault 0 0% 48.64% 345ms 8.52% KSP_PCApply (partial-inline) 50ms 1.24% 49.88% 262ms 6.47% BidomainSolver::SetupLinearSystem 130ms 3.21% 53.09% 242ms 5.98% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 28ms 0.69% 53.78% 234ms 5.78% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 31ms 0.77% 54.55% 229ms 5.66% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 198ms 4.89% 59.44% 198ms 4.89% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 0 0% 59.44% 194ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 38ms 0.94% 60.38% 194ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 0 0% 60.38% 161ms 3.98% MatNullSpaceRemove 1ms 0.025% 60.40% 159ms 3.93% std::pow (inline) 19ms 0.47% 60.87% 158ms 3.90% __pow 18ms 0.44% 61.31% 138ms 3.41% __ieee754_pow_fma 134ms 3.31% 64.62% 134ms 3.31% ddot_ 62ms 1.53% 66.16% 130ms 3.21% __GI___exp 128ms 3.16% 69.32% 128ms 3.16% __ieee754_log_fma 0 0% 69.32% 126ms 3.11% boost::numeric::ublas::norm_inf (inline) 76ms 1.88% 71.20% 126ms 3.11% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.20% 126ms 3.11% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.20% 126ms 3.11% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 0 0% 71.20% 105ms 2.59% VecDuplicate 0 0% 71.20% 99ms 2.45% VecMDot 7ms 0.17% 71.37% 99ms 2.45% VecMXDot_Private 1ms 0.025% 71.39% 88ms 2.17% VecTDot 83ms 2.05% 73.44% 86ms 2.12% exp_inline (inline) 2ms 0.049% 73.49% 78ms 1.93% VecNorm 0 0% 73.49% 77ms 1.90% VecTDot_Seq 0 0% 73.49% 77ms 1.90% VecXDot_Seq_Private.constprop.0 20ms 0.49% 73.99% 74ms 1.83% MPI_Wtime 40ms 0.99% 74.98% 72ms 1.78% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 70ms 1.73% 76.70% 71ms 1.75% VecMDot_Seq 58ms 1.43% 78.14% 67ms 1.66% __ieee754_exp_fma 4ms 0.099% 78.24% 63ms 1.56% VecDuplicate_Seq 0 0% 78.24% 63ms 1.56% VecNorm_Seq 6ms 0.15% 78.38% 62ms 1.53% VecMAXPY 12ms 0.3% 78.68% 61ms 1.51% PetscLogEventBeginDefault 1ms 0.025% 78.71% 54ms 1.33% __GI___clock_gettime 53ms 1.31% 80.01% 53ms 1.31% [[vdso]] 1ms 0.025% 80.04% 51ms 1.26% VecDestroy 9ms 0.22% 80.26% 48ms 1.19% PetscLogEventEndDefault 0 0% 80.26% 44ms 1.09% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 80.26% 44ms 1.09% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 80.26% 44ms 1.09% boost_numeric_ublas_abs (inline) 44ms 1.09% 81.35% 44ms 1.09% std::abs (inline) 5ms 0.12% 81.47% 42ms 1.04% VecDuplicate_MPI (partial-inline) 9ms 0.22% 81.69% 41ms 1.01% PetscMallocA 1ms 0.025% 81.72% 40ms 0.99% PetscTimeSubtract (inline) 37ms 0.91% 82.63% 39ms 0.96% VecMAXPY_Seq 7ms 0.17% 82.81% 36ms 0.89% VecCopy 3ms 0.074% 82.88% 36ms 0.89% VecSetType 34ms 0.84% 83.72% 34ms 0.84% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 83.72% 34ms 0.84% PetscMemcpy (inline) 0 0% 83.72% 34ms 0.84% VecCreate 33ms 0.82% 84.54% 33ms 0.82% [libchaste_heart.so] 2ms 0.049% 84.58% 32ms 0.79% PetscMallocAlign (partial-inline) 0 0% 84.58% 31ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 84.58% 31ms 0.77% PMPI_Init_thread 0 0% 84.58% 31ms 0.77% PetscInitialize 0 0% 84.58% 31ms 0.77% PetscSetup::setUpWorld 0 0% 84.58% 31ms 0.77% PetscSetupUtils::CommonSetup 0 0% 84.58% 31ms 0.77% PetscSetupUtils::InitialisePetsc 31ms 0.77% 85.35% 31ms 0.77% log_inline (inline) 0 0% 85.35% 31ms 0.77% ompi_mpi_init 11ms 0.27% 85.62% 29ms 0.72% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 85.62% 29ms 0.72% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.025% 85.65% 29ms 0.72% VecCreate_Seq 1ms 0.025% 85.67% 28ms 0.69% VecAYPX 5ms 0.12% 85.80% 27ms 0.67% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 27ms 0.67% 86.46% 27ms 0.67% __memcpy_avx_unaligned_erms 0 0% 86.46% 26ms 0.64% VecNorm_MPI 0 0% 86.46% 26ms 0.64% VecNorm_MPI_Default.constprop.0 2ms 0.049% 86.51% 24ms 0.59% 7ms 0.17% 86.68% 24ms 0.59% AbstractCardiacCell::SetVoltage 2ms 0.049% 86.73% 24ms 0.59% VecCreate_MPI_Private 4ms 0.099% 86.83% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 0 0% 86.83% 23ms 0.57% PetscMemzero (inline) 23ms 0.57% 87.40% 23ms 0.57% VecAYPX_Seq 0 0% 87.40% 22ms 0.54% orte_init 13ms 0.32% 87.72% 22ms 0.54% std::__cxx11::basic_string::basic_string 0 0% 87.72% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 1ms 0.025% 87.75% 21ms 0.52% KSP_RemoveNullSpace (inline)