File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3503ms, 86.01% of 4073ms total Dropped 458 nodes (cum <= 20.36ms) flat flat% sum% cum cum% 0 0% 0% 4065ms 99.80% __libc_start_call_main 0 0% 0% 4065ms 99.80% __libc_start_main_impl 0 0% 0% 4065ms 99.80% _start 0 0% 0% 4062ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4062ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4062ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4062ms 99.73% main 0 0% 0% 4026ms 98.85% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4026ms 98.85% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4026ms 98.85% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.025% 0.025% 4018ms 98.65% AbstractCardiacProblem::Solve 2ms 0.049% 0.074% 4006ms 98.36% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.074% 2063ms 50.65% LinearSystem::Solve 0 0% 0.074% 2016ms 49.50% KSPSolve 9ms 0.22% 0.29% 2016ms 49.50% KSPSolve_Private 1ms 0.025% 0.32% 1970ms 48.37% KSPSolve_CG 8ms 0.2% 0.52% 1637ms 40.19% AbstractCardiacTissue::SolveCellSystems 3ms 0.074% 0.59% 1280ms 31.43% PCApply 2ms 0.049% 0.64% 1267ms 31.11% PCApply_BJacobi_Singleblock 63ms 1.55% 2.19% 1118ms 27.45% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.19% 1076ms 26.42% KSPSolve_PREONLY (partial-inline) 0 0% 2.19% 1022ms 25.09% KSP_PCApply.lto_priv.7 3ms 0.074% 2.26% 1015ms 24.92% MatSolve 0 0% 2.26% 1015ms 24.92% PCApply_ICC 1000ms 24.55% 26.81% 1008ms 24.75% MatSolve_SeqSBAIJ_1_NaturalOrdering 217ms 5.33% 32.14% 975ms 23.94% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 3ms 0.074% 32.21% 545ms 13.38% MatMult 5ms 0.12% 32.33% 532ms 13.06% MatMult_SeqAIJ 519ms 12.74% 45.08% 531ms 13.04% MatMult_SeqAIJ_Inode (inline) 17ms 0.42% 45.49% 485ms 11.91% AbstractCardiacTissue::UpdateCaches (inline) 128ms 3.14% 48.64% 454ms 11.15% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 64ms 1.57% 50.21% 441ms 10.83% CardiacNewtonSolver::Solve 3ms 0.074% 50.28% 361ms 8.86% KSPConvergedDefault 0 0% 50.28% 360ms 8.84% KSP_PCApply (partial-inline) 48ms 1.18% 51.46% 268ms 6.58% BidomainSolver::SetupLinearSystem 134ms 3.29% 54.75% 257ms 6.31% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 29ms 0.71% 55.46% 225ms 5.52% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 55.46% 195ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 27ms 0.66% 56.13% 195ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 42ms 1.03% 57.16% 195ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 4ms 0.098% 57.26% 164ms 4.03% MatNullSpaceRemove 159ms 3.90% 61.16% 159ms 3.90% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 20ms 0.49% 61.65% 148ms 3.63% __pow 0 0% 61.65% 148ms 3.63% std::pow (inline) 131ms 3.22% 64.87% 132ms 3.24% __ieee754_log_fma 20ms 0.49% 65.36% 127ms 3.12% __ieee754_pow_fma 123ms 3.02% 68.38% 123ms 3.02% ddot_ 54ms 1.33% 69.70% 120ms 2.95% __GI___exp 0 0% 69.70% 112ms 2.75% VecMDot 13ms 0.32% 70.02% 112ms 2.75% VecMXDot_Private 0 0% 70.02% 110ms 2.70% boost::numeric::ublas::norm_inf (inline) 70ms 1.72% 71.74% 110ms 2.70% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.74% 110ms 2.70% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.74% 110ms 2.70% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 1ms 0.025% 71.77% 96ms 2.36% VecDuplicate 24ms 0.59% 72.35% 90ms 2.21% MPI_Wtime 2ms 0.049% 72.40% 89ms 2.19% VecTDot 43ms 1.06% 73.46% 77ms 1.89% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 1ms 0.025% 73.48% 75ms 1.84% VecNorm 0 0% 73.48% 75ms 1.84% VecTDot_Seq 0 0% 73.48% 75ms 1.84% VecXDot_Seq_Private.constprop.0 15ms 0.37% 73.85% 72ms 1.77% PetscLogEventEndDefault 64ms 1.57% 75.42% 70ms 1.72% VecMDot_Seq 60ms 1.47% 76.90% 69ms 1.69% exp_inline (inline) 66ms 1.62% 78.52% 66ms 1.62% [[vdso]] 0 0% 78.52% 66ms 1.62% __GI___clock_gettime 3ms 0.074% 78.59% 65ms 1.60% VecDestroy 1ms 0.025% 78.62% 64ms 1.57% VecNorm_Seq 56ms 1.37% 79.99% 61ms 1.50% __ieee754_exp_fma 11ms 0.27% 80.26% 58ms 1.42% PetscLogEventBeginDefault 0 0% 80.26% 51ms 1.25% VecDuplicate_MPI 2ms 0.049% 80.31% 48ms 1.18% VecCreate 5ms 0.12% 80.43% 48ms 1.18% VecMAXPY 8ms 0.2% 80.63% 47ms 1.15% PetscMallocA 45ms 1.10% 81.73% 45ms 1.10% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 81.73% 44ms 1.08% VecDuplicate_Seq 0 0% 81.73% 39ms 0.96% PetscMallocAlign 0 0% 81.73% 38ms 0.93% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 81.73% 38ms 0.93% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 81.73% 38ms 0.93% boost_numeric_ublas_abs (inline) 38ms 0.93% 82.67% 38ms 0.93% log_inline (inline) 38ms 0.93% 83.60% 38ms 0.93% std::abs (inline) 5ms 0.12% 83.72% 36ms 0.88% VecCopy 34ms 0.83% 84.56% 34ms 0.83% [libchaste_heart.so] 0 0% 84.56% 31ms 0.76% CxxTest::RealWorldDescription::setUp 0 0% 84.56% 31ms 0.76% PetscInitialize 0 0% 84.56% 31ms 0.76% PetscSetup::setUpWorld 0 0% 84.56% 31ms 0.76% PetscSetupUtils::CommonSetup 0 0% 84.56% 31ms 0.76% PetscSetupUtils::InitialisePetsc 0 0% 84.56% 30ms 0.74% PMPI_Init_thread 0 0% 84.56% 30ms 0.74% VecNorm_MPI 0 0% 84.56% 30ms 0.74% VecNorm_MPI_Default.constprop.0 0 0% 84.56% 30ms 0.74% ompi_mpi_init 4ms 0.098% 84.66% 29ms 0.71% PetscHeaderDestroy_Private 6ms 0.15% 84.80% 28ms 0.69% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.80% 28ms 0.69% AbstractBidomainSolver::FinaliseLinearSystem 2ms 0.049% 84.85% 26ms 0.64% 22ms 0.54% 85.39% 26ms 0.64% VecMAXPY_Seq 2ms 0.049% 85.44% 25ms 0.61% __posix_memalign 7ms 0.17% 85.61% 24ms 0.59% __GI___libc_malloc 1ms 0.025% 85.64% 23ms 0.56% VecAYPX 0 0% 85.64% 23ms 0.56% orte_init 3ms 0.074% 85.71% 22ms 0.54% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 9ms 0.22% 85.93% 22ms 0.54% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 1ms 0.025% 85.96% 22ms 0.54% VecCreate_MPI_Private 1ms 0.025% 85.98% 22ms 0.54% VecSetType 0 0% 85.98% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 1ms 0.025% 86.01% 21ms 0.52% PetscHeaderCreate_Private