File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3545ms, 87.29% of 4061ms total Dropped 461 nodes (cum <= 20.30ms) flat flat% sum% cum cum% 0 0% 0% 4054ms 99.83% __libc_start_call_main 0 0% 0% 4054ms 99.83% __libc_start_main_impl 0 0% 0% 4054ms 99.83% _start 0 0% 0% 4051ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4051ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4051ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4051ms 99.75% main 0 0% 0% 4018ms 98.94% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4018ms 98.94% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4018ms 98.94% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.025% 0.025% 4011ms 98.77% AbstractCardiacProblem::Solve 1ms 0.025% 0.049% 3996ms 98.40% AbstractDynamicLinearPdeSolver::Solve 1ms 0.025% 0.074% 2051ms 50.50% LinearSystem::Solve 0 0% 0.074% 2005ms 49.37% KSPSolve 4ms 0.098% 0.17% 2005ms 49.37% KSPSolve_Private 2ms 0.049% 0.22% 1950ms 48.02% KSPSolve_CG 9ms 0.22% 0.44% 1662ms 40.93% AbstractCardiacTissue::SolveCellSystems 3ms 0.074% 0.52% 1249ms 30.76% PCApply 1ms 0.025% 0.54% 1234ms 30.39% PCApply_BJacobi_Singleblock 78ms 1.92% 2.46% 1123ms 27.65% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.46% 1013ms 24.94% KSPSolve_PREONLY (partial-inline) 0 0% 2.46% 999ms 24.60% KSP_PCApply.lto_priv.7 208ms 5.12% 7.58% 976ms 24.03% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 9ms 0.22% 7.81% 967ms 23.81% MatSolve 0 0% 7.81% 967ms 23.81% PCApply_ICC 940ms 23.15% 30.95% 951ms 23.42% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 5ms 0.12% 31.08% 549ms 13.52% MatMult 4ms 0.098% 31.17% 537ms 13.22% MatMult_SeqAIJ 528ms 13.00% 44.18% 537ms 13.22% MatMult_SeqAIJ_Inode (inline) 16ms 0.39% 44.57% 497ms 12.24% AbstractCardiacTissue::UpdateCaches (inline) 123ms 3.03% 47.60% 467ms 11.50% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 44ms 1.08% 48.68% 448ms 11.03% CardiacNewtonSolver::Solve 2ms 0.049% 48.73% 352ms 8.67% KSPConvergedDefault 0 0% 48.73% 342ms 8.42% KSP_PCApply (partial-inline) 135ms 3.32% 52.06% 265ms 6.53% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 42ms 1.03% 53.09% 251ms 6.18% BidomainSolver::SetupLinearSystem 22ms 0.54% 53.63% 218ms 5.37% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 26ms 0.64% 54.27% 212ms 5.22% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 54.27% 194ms 4.78% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 41ms 1.01% 55.28% 194ms 4.78% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 183ms 4.51% 59.79% 183ms 4.51% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 59.81% 161ms 3.96% std::pow (inline) 14ms 0.34% 60.16% 160ms 3.94% __pow 2ms 0.049% 60.21% 159ms 3.92% MatNullSpaceRemove 16ms 0.39% 60.60% 145ms 3.57% __ieee754_pow_fma 0 0% 60.60% 142ms 3.50% VecDuplicate 127ms 3.13% 63.73% 128ms 3.15% __ieee754_log_fma 0 0% 63.73% 125ms 3.08% boost::numeric::ublas::norm_inf (inline) 81ms 1.99% 65.72% 125ms 3.08% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 65.72% 125ms 3.08% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 65.72% 125ms 3.08% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 49ms 1.21% 66.93% 124ms 3.05% __GI___exp 107ms 2.63% 69.56% 107ms 2.63% ddot_ 0 0% 69.56% 106ms 2.61% VecMDot 9ms 0.22% 69.79% 106ms 2.61% VecMXDot_Private 30ms 0.74% 70.52% 92ms 2.27% MPI_Wtime 88ms 2.17% 72.69% 90ms 2.22% exp_inline (inline) 3ms 0.074% 72.77% 83ms 2.04% VecDuplicate_Seq 5ms 0.12% 72.89% 82ms 2.02% VecTDot 70ms 1.72% 74.61% 74ms 1.82% __ieee754_exp_fma 10ms 0.25% 74.86% 72ms 1.77% PetscLogEventEndDefault 1ms 0.025% 74.88% 71ms 1.75% VecNorm 62ms 1.53% 76.41% 66ms 1.63% VecMDot_Seq 41ms 1.01% 77.42% 64ms 1.58% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 0 0% 77.42% 64ms 1.58% VecTDot_Seq 1ms 0.025% 77.44% 64ms 1.58% VecXDot_Seq_Private.constprop.0 10ms 0.25% 77.69% 63ms 1.55% PetscLogEventBeginDefault 2ms 0.049% 77.74% 61ms 1.50% __GI___clock_gettime 4ms 0.098% 77.84% 59ms 1.45% VecDuplicate_MPI 59ms 1.45% 79.29% 59ms 1.45% [[vdso]] 3ms 0.074% 79.36% 58ms 1.43% VecDestroy 0 0% 79.36% 58ms 1.43% VecNorm_Seq 5ms 0.12% 79.49% 57ms 1.40% VecCreate 0 0% 79.49% 51ms 1.26% VecMAXPY 4ms 0.098% 79.59% 48ms 1.18% PetscMallocA 44ms 1.08% 80.67% 44ms 1.08% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 2ms 0.049% 80.72% 44ms 1.08% PetscMallocAlign (partial-inline) 0 0% 80.72% 42ms 1.03% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 80.72% 42ms 1.03% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 80.72% 42ms 1.03% boost_numeric_ublas_abs (inline) 42ms 1.03% 81.75% 42ms 1.03% std::abs (inline) 6ms 0.15% 81.90% 39ms 0.96% VecSetType 2ms 0.049% 81.95% 35ms 0.86% VecCreate_MPI_Private 35ms 0.86% 82.81% 35ms 0.86% log_inline (inline) 34ms 0.84% 83.65% 34ms 0.84% [libchaste_heart.so] 10ms 0.25% 83.90% 32ms 0.79% __GI___libc_malloc 4ms 0.098% 83.99% 31ms 0.76% VecCopy 30ms 0.74% 84.73% 31ms 0.76% VecMAXPY_Seq 2ms 0.049% 84.78% 30ms 0.74% PetscHeaderCreate_Private 0 0% 84.78% 29ms 0.71% CxxTest::RealWorldDescription::setUp 0 0% 84.78% 29ms 0.71% PMPI_Init_thread 0 0% 84.78% 29ms 0.71% PetscInitialize 0 0% 84.78% 29ms 0.71% PetscSetup::setUpWorld 0 0% 84.78% 29ms 0.71% PetscSetupUtils::CommonSetup 0 0% 84.78% 29ms 0.71% PetscSetupUtils::InitialisePetsc 0 0% 84.78% 29ms 0.71% ompi_mpi_init 0 0% 84.78% 28ms 0.69% PetscMemcpy (inline) 1ms 0.025% 84.81% 27ms 0.66% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.074% 84.88% 26ms 0.64% PetscHeaderDestroy_Private 0 0% 84.88% 26ms 0.64% PetscMemzero (inline) 3ms 0.074% 84.95% 26ms 0.64% __posix_memalign 0 0% 84.95% 25ms 0.62% 7ms 0.17% 85.13% 25ms 0.62% AbstractBidomainSolver::CheckCompatibilityCondition 12ms 0.3% 85.42% 25ms 0.62% __GI___libc_free 25ms 0.62% 86.04% 25ms 0.62% __memset_avx2_unaligned_erms 24ms 0.59% 86.63% 24ms 0.59% DistributedVector::Iterator::operator!= 15ms 0.37% 87.00% 24ms 0.59% PetscStageLogGetCurrent 1ms 0.025% 87.02% 24ms 0.59% VecAXPY 4ms 0.098% 87.12% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 2ms 0.049% 87.17% 23ms 0.57% KSP_RemoveNullSpace (inline) 0 0% 87.17% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 3ms 0.074% 87.24% 21ms 0.52% PetscFreeAlign 2ms 0.049% 87.29% 21ms 0.52% VecAYPX 0 0% 87.29% 21ms 0.52% VecCreate_Seq