File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3514ms, 86.34% of 4070ms total Dropped 481 nodes (cum <= 20.35ms) flat flat% sum% cum cum% 0 0% 0% 4063ms 99.83% __libc_start_call_main 0 0% 0% 4063ms 99.83% __libc_start_main_impl 0 0% 0% 4063ms 99.83% _start 0 0% 0% 4061ms 99.78% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4061ms 99.78% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4061ms 99.78% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4061ms 99.78% main 0 0% 0% 4023ms 98.85% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4023ms 98.85% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4023ms 98.85% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.025% 0.025% 4014ms 98.62% AbstractCardiacProblem::Solve 0 0% 0.025% 3996ms 98.18% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.025% 2042ms 50.17% LinearSystem::Solve 0 0% 0.025% 2009ms 49.36% KSPSolve 5ms 0.12% 0.15% 2009ms 49.36% KSPSolve_Private 0 0% 0.15% 1962ms 48.21% KSPSolve_CG 6ms 0.15% 0.29% 1660ms 40.79% AbstractCardiacTissue::SolveCellSystems 4ms 0.098% 0.39% 1264ms 31.06% PCApply 3ms 0.074% 0.47% 1251ms 30.74% PCApply_BJacobi_Singleblock 64ms 1.57% 2.04% 1097ms 26.95% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.04% 1045ms 25.68% KSPSolve_PREONLY (partial-inline) 0 0% 2.04% 992ms 24.37% KSP_PCApply.lto_priv.7 1ms 0.025% 2.06% 983ms 24.15% PCApply_ICC 2ms 0.049% 2.11% 982ms 24.13% MatSolve 968ms 23.78% 25.90% 973ms 23.91% MatSolve_SeqSBAIJ_1_NaturalOrdering 216ms 5.31% 31.20% 960ms 23.59% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 20ms 0.49% 31.70% 520ms 12.78% AbstractCardiacTissue::UpdateCaches (inline) 4ms 0.098% 31.79% 520ms 12.78% MatMult 5ms 0.12% 31.92% 509ms 12.51% MatMult_SeqAIJ (partial-inline) 496ms 12.19% 44.10% 509ms 12.51% MatMult_SeqAIJ_Inode (inline) 133ms 3.27% 47.37% 479ms 11.77% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 50ms 1.23% 48.60% 433ms 10.64% CardiacNewtonSolver::Solve 3ms 0.074% 48.67% 384ms 9.43% KSPConvergedDefault 0 0% 48.67% 368ms 9.04% KSP_PCApply (partial-inline) 148ms 3.64% 52.31% 269ms 6.61% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 46ms 1.13% 53.44% 255ms 6.27% BidomainSolver::SetupLinearSystem 24ms 0.59% 54.03% 223ms 5.48% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 32ms 0.79% 54.82% 212ms 5.21% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 54.82% 199ms 4.89% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 43ms 1.06% 55.87% 199ms 4.89% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 173ms 4.25% 60.12% 173ms 4.25% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 60.15% 163ms 4.00% MatNullSpaceRemove 145ms 3.56% 63.71% 146ms 3.59% __ieee754_log_fma 1ms 0.025% 63.73% 143ms 3.51% std::pow (inline) 19ms 0.47% 64.20% 142ms 3.49% __pow 135ms 3.32% 67.52% 135ms 3.32% ddot_ 67ms 1.65% 69.16% 127ms 3.12% __GI___exp 0 0% 69.16% 126ms 3.10% VecDuplicate 9ms 0.22% 69.39% 122ms 3.00% __ieee754_pow_fma 0 0% 69.39% 104ms 2.56% boost::numeric::ublas::norm_inf (inline) 59ms 1.45% 70.84% 104ms 2.56% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 70.84% 104ms 2.56% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 70.84% 104ms 2.56% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 1ms 0.025% 70.86% 97ms 2.38% VecMDot 5ms 0.12% 70.98% 96ms 2.36% VecMXDot_Private 2ms 0.049% 71.03% 94ms 2.31% VecTDot 28ms 0.69% 71.72% 86ms 2.11% MPI_Wtime 82ms 2.01% 73.73% 83ms 2.04% exp_inline (inline) 1ms 0.025% 73.76% 82ms 2.01% VecNorm 0 0% 73.76% 78ms 1.92% VecTDot_Seq 0 0% 73.76% 78ms 1.92% VecXDot_Seq_Private.constprop.0 1ms 0.025% 73.78% 73ms 1.79% VecDuplicate_Seq 68ms 1.67% 75.45% 71ms 1.74% VecMDot_Seq 36ms 0.88% 76.34% 70ms 1.72% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 11ms 0.27% 76.61% 69ms 1.70% PetscLogEventBeginDefault 0 0% 76.61% 68ms 1.67% VecNorm_Seq 0 0% 76.61% 65ms 1.60% VecDestroy 3ms 0.074% 76.68% 65ms 1.60% VecMAXPY 54ms 1.33% 78.01% 59ms 1.45% __ieee754_exp_fma 57ms 1.40% 79.41% 57ms 1.40% [[vdso]] 0 0% 79.41% 57ms 1.40% __GI___clock_gettime 0 0% 79.41% 54ms 1.33% KSP_RemoveNullSpace (inline) 13ms 0.32% 79.73% 54ms 1.33% PetscLogEventEndDefault 2ms 0.049% 79.78% 53ms 1.30% VecDuplicate_MPI 3ms 0.074% 79.85% 49ms 1.20% VecCreate 6ms 0.15% 80.00% 46ms 1.13% PetscMallocA 41ms 1.01% 81.01% 43ms 1.06% VecMAXPY_Seq 0 0% 81.01% 42ms 1.03% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 81.01% 42ms 1.03% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 81.01% 42ms 1.03% boost_numeric_ublas_abs (inline) 42ms 1.03% 82.04% 42ms 1.03% std::abs (inline) 2ms 0.049% 82.09% 40ms 0.98% PetscMallocAlign 0 0% 82.09% 37ms 0.91% VecSetType 13ms 0.32% 82.41% 34ms 0.84% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.41% 34ms 0.84% AbstractBidomainSolver::FinaliseLinearSystem 34ms 0.84% 83.24% 34ms 0.84% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 83.24% 33ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 83.24% 33ms 0.81% PMPI_Init_thread 0 0% 83.24% 33ms 0.81% PetscInitialize 0 0% 83.24% 33ms 0.81% PetscSetup::setUpWorld 0 0% 83.24% 33ms 0.81% PetscSetupUtils::CommonSetup 0 0% 83.24% 33ms 0.81% PetscSetupUtils::InitialisePetsc 0 0% 83.24% 33ms 0.81% ompi_mpi_init 3ms 0.074% 83.32% 32ms 0.79% VecCreate_MPI_Private 2ms 0.049% 83.37% 31ms 0.76% VecCopy 31ms 0.76% 84.13% 31ms 0.76% [libchaste_heart.so] 5ms 0.12% 84.25% 30ms 0.74% PetscHeaderDestroy_Private 10ms 0.25% 84.50% 29ms 0.71% __GI___libc_malloc 29ms 0.71% 85.21% 29ms 0.71% log_inline (inline) 1ms 0.025% 85.23% 28ms 0.69% 1ms 0.025% 85.26% 27ms 0.66% __posix_memalign 8ms 0.2% 85.45% 25ms 0.61% PetscOptionsFindPair 3ms 0.074% 85.53% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 5ms 0.12% 85.65% 24ms 0.59% VecAXPY 0 0% 85.65% 24ms 0.59% VecNorm_MPI 1ms 0.025% 85.68% 24ms 0.59% VecNorm_MPI_Default.constprop.0 0 0% 85.68% 24ms 0.59% orte_init 5ms 0.12% 85.80% 23ms 0.57% PetscHeaderCreate_Private 7ms 0.17% 85.97% 22ms 0.54% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 0 0% 85.97% 22ms 0.54% VecCreate_Seq 0 0% 85.97% 21ms 0.52% PetscMemcpy (inline) 15ms 0.37% 86.34% 21ms 0.52% _int_malloc