File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3761ms, 86.22% of 4362ms total Dropped 485 nodes (cum <= 21.81ms) flat flat% sum% cum cum% 0 0% 0% 4351ms 99.75% __libc_start_call_main 0 0% 0% 4351ms 99.75% __libc_start_main_impl 0 0% 0% 4351ms 99.75% _start 0 0% 0% 4348ms 99.68% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4348ms 99.68% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4348ms 99.68% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4348ms 99.68% main 0 0% 0% 4308ms 98.76% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4308ms 98.76% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4308ms 98.76% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 2ms 0.046% 0.046% 4300ms 98.58% AbstractCardiacProblem::Solve 0 0% 0.046% 4284ms 98.21% AbstractDynamicLinearPdeSolver::Solve 1ms 0.023% 0.069% 2049ms 46.97% LinearSystem::Solve 0 0% 0.069% 2005ms 45.97% KSPSolve 4ms 0.092% 0.16% 2005ms 45.97% KSPSolve_Private 3ms 0.069% 0.23% 1967ms 45.09% KSPSolve_CG 17ms 0.39% 0.62% 1924ms 44.11% AbstractCardiacTissue::SolveCellSystems 3ms 0.069% 0.69% 1279ms 29.32% PCApply 1ms 0.023% 0.71% 1267ms 29.05% PCApply_BJacobi_Singleblock 54ms 1.24% 1.95% 1256ms 28.79% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 272ms 6.24% 8.18% 1110ms 25.45% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 0 0% 8.18% 1081ms 24.78% KSPSolve_PREONLY (partial-inline) 3ms 0.069% 8.25% 1006ms 23.06% MatSolve 0 0% 8.25% 1006ms 23.06% PCApply_ICC 992ms 22.74% 30.99% 999ms 22.90% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 30.99% 992ms 22.74% KSP_PCApply.lto_priv.7 11ms 0.25% 31.25% 602ms 13.80% AbstractCardiacTissue::UpdateCaches (inline) 114ms 2.61% 33.86% 559ms 12.82% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 5ms 0.11% 33.98% 513ms 11.76% MatMult 4ms 0.092% 34.07% 497ms 11.39% MatMult_SeqAIJ 486ms 11.14% 45.21% 497ms 11.39% MatMult_SeqAIJ_Inode (inline) 64ms 1.47% 46.68% 473ms 10.84% CardiacNewtonSolver::Solve 0 0% 46.68% 403ms 9.24% KSP_PCApply (partial-inline) 2ms 0.046% 46.72% 388ms 8.90% KSPConvergedDefault 60ms 1.38% 48.10% 346ms 7.93% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 172ms 3.94% 52.04% 295ms 6.76% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 63ms 1.44% 53.48% 279ms 6.40% BidomainSolver::SetupLinearSystem 277ms 6.35% 59.83% 277ms 6.35% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 21ms 0.48% 60.32% 218ms 5.00% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 60.32% 191ms 4.38% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 35ms 0.8% 61.12% 191ms 4.38% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 1ms 0.023% 61.14% 188ms 4.31% MatNullSpaceRemove 2ms 0.046% 61.19% 176ms 4.03% std::pow (inline) 24ms 0.55% 61.74% 172ms 3.94% __pow 17ms 0.39% 62.13% 146ms 3.35% __ieee754_pow_fma 143ms 3.28% 65.41% 143ms 3.28% ddot_ 129ms 2.96% 68.36% 131ms 3.00% __ieee754_log_fma 47ms 1.08% 69.44% 119ms 2.73% __GI___exp 29ms 0.66% 70.11% 106ms 2.43% MPI_Wtime 0 0% 70.11% 103ms 2.36% VecMDot 4ms 0.092% 70.20% 103ms 2.36% VecMXDot_Private 2ms 0.046% 70.24% 100ms 2.29% VecNorm 0 0% 70.24% 95ms 2.18% boost::numeric::ublas::norm_inf (inline) 66ms 1.51% 71.76% 95ms 2.18% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.76% 95ms 2.18% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.76% 95ms 2.18% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 1ms 0.023% 71.78% 92ms 2.11% VecTDot 53ms 1.22% 72.99% 88ms 2.02% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 3ms 0.069% 73.06% 84ms 1.93% VecMAXPY 1ms 0.023% 73.09% 83ms 1.90% VecNorm_Seq 7ms 0.16% 73.25% 82ms 1.88% PetscLogEventBeginDefault 0 0% 73.25% 82ms 1.88% VecDuplicate 0 0% 73.25% 79ms 1.81% VecTDot_Seq 0 0% 73.25% 79ms 1.81% VecXDot_Seq_Private.constprop.0 1ms 0.023% 73.27% 76ms 1.74% __GI___clock_gettime 75ms 1.72% 74.99% 75ms 1.72% [[vdso]] 19ms 0.44% 75.42% 73ms 1.67% PetscLogEventEndDefault 69ms 1.58% 77.01% 72ms 1.65% VecMDot_Seq 62ms 1.42% 78.43% 66ms 1.51% exp_inline (inline) 63ms 1.44% 79.87% 63ms 1.44% log_inline (inline) 43ms 0.99% 80.86% 52ms 1.19% __ieee754_exp_fma 0 0% 80.86% 50ms 1.15% VecDuplicate_Seq 49ms 1.12% 81.98% 50ms 1.15% VecMAXPY_Seq 0 0% 81.98% 44ms 1.01% VecNorm_MPI 0 0% 81.98% 44ms 1.01% VecNorm_MPI_Default.constprop.0 3ms 0.069% 82.05% 41ms 0.94% VecCreate 40ms 0.92% 82.97% 40ms 0.92% [libchaste_heart.so] 1ms 0.023% 82.99% 39ms 0.89% VecDestroy 3ms 0.069% 83.06% 38ms 0.87% PetscMallocA 0 0% 83.06% 36ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 83.06% 36ms 0.83% PetscInitialize 0 0% 83.06% 36ms 0.83% PetscSetup::setUpWorld 0 0% 83.06% 36ms 0.83% PetscSetupUtils::CommonSetup 0 0% 83.06% 36ms 0.83% PetscSetupUtils::InitialisePetsc 35ms 0.8% 83.86% 35ms 0.8% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 83.86% 35ms 0.8% PMPI_Init_thread 1ms 0.023% 83.88% 35ms 0.8% PetscMallocAlign 2ms 0.046% 83.93% 35ms 0.8% VecCopy 0 0% 83.93% 35ms 0.8% ompi_mpi_init 1ms 0.023% 83.95% 32ms 0.73% VecDuplicate_MPI 3ms 0.069% 84.02% 29ms 0.66% 0 0% 84.02% 29ms 0.66% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 84.02% 29ms 0.66% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 84.02% 29ms 0.66% boost_numeric_ublas_abs (inline) 29ms 0.66% 84.69% 29ms 0.66% std::abs (inline) 0 0% 84.69% 27ms 0.62% AbstractBidomainSolver::FinaliseLinearSystem 7ms 0.16% 84.85% 25ms 0.57% AbstractBidomainSolver::CheckCompatibilityCondition 2ms 0.046% 84.89% 25ms 0.57% VecAXPY 0 0% 84.89% 25ms 0.57% VecAYPX 25ms 0.57% 85.47% 25ms 0.57% [libm.so.6] 0 0% 85.47% 22ms 0.5% AbstractFeVolumeIntegralAssembler::DoAssemble 7ms 0.16% 85.63% 22ms 0.5% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 4ms 0.092% 85.72% 22ms 0.5% PetscHeaderDestroy_Private 22ms 0.5% 86.22% 22ms 0.5% __memcpy_avx_unaligned_erms 0 0% 86.22% 22ms 0.5% orte_init