File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3605ms, 89.17% of 4043ms total Dropped 442 nodes (cum <= 20.21ms) flat flat% sum% cum cum% 0 0% 0% 4036ms 99.83% __libc_start_call_main 0 0% 0% 4036ms 99.83% __libc_start_main_impl 0 0% 0% 4036ms 99.83% _start 0 0% 0% 4033ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4033ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4033ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4033ms 99.75% main 0 0% 0% 3996ms 98.84% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3996ms 98.84% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3996ms 98.84% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 3988ms 98.64% AbstractCardiacProblem::Solve 0 0% 0% 3971ms 98.22% AbstractDynamicLinearPdeSolver::Solve 0 0% 0% 1955ms 48.36% LinearSystem::Solve 0 0% 0% 1920ms 47.49% KSPSolve 4ms 0.099% 0.099% 1920ms 47.49% KSPSolve_Private 2ms 0.049% 0.15% 1884ms 46.60% KSPSolve_CG 11ms 0.27% 0.42% 1670ms 41.31% AbstractCardiacTissue::SolveCellSystems 1ms 0.025% 0.45% 1230ms 30.42% PCApply 0 0% 0.45% 1222ms 30.23% PCApply_BJacobi_Singleblock 59ms 1.46% 1.90% 1114ms 27.55% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 1.90% 1039ms 25.70% KSPSolve_PREONLY (partial-inline) 226ms 5.59% 7.49% 991ms 24.51% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 3ms 0.074% 7.57% 978ms 24.19% MatSolve 0 0% 7.57% 978ms 24.19% PCApply_ICC 0 0% 7.57% 971ms 24.02% KSP_PCApply.lto_priv.7 957ms 23.67% 31.24% 968ms 23.94% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 4ms 0.099% 31.34% 530ms 13.11% MatMult 3ms 0.074% 31.41% 523ms 12.94% MatMult_SeqAIJ 517ms 12.79% 44.20% 523ms 12.94% MatMult_SeqAIJ_Inode (inline) 16ms 0.4% 44.60% 510ms 12.61% AbstractCardiacTissue::UpdateCaches (inline) 137ms 3.39% 47.98% 482ms 11.92% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 50ms 1.24% 49.22% 433ms 10.71% CardiacNewtonSolver::Solve 3ms 0.074% 49.30% 357ms 8.83% KSPConvergedDefault 0 0% 49.30% 347ms 8.58% KSP_PCApply (partial-inline) 58ms 1.43% 50.73% 303ms 7.49% BidomainSolver::SetupLinearSystem 135ms 3.34% 54.07% 255ms 6.31% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 27ms 0.67% 54.74% 220ms 5.44% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 22ms 0.54% 55.28% 217ms 5.37% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 55.28% 194ms 4.80% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 42ms 1.04% 56.32% 194ms 4.80% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 184ms 4.55% 60.87% 184ms 4.55% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 0 0% 60.87% 156ms 3.86% std::pow (inline) 18ms 0.45% 61.32% 155ms 3.83% __pow 140ms 3.46% 64.78% 142ms 3.51% __ieee754_log_fma 20ms 0.49% 65.27% 137ms 3.39% __ieee754_pow_fma 0 0% 65.27% 132ms 3.26% MatNullSpaceRemove 132ms 3.26% 68.54% 132ms 3.26% ddot_ 65ms 1.61% 70.15% 129ms 3.19% __GI___exp 0 0% 70.15% 123ms 3.04% boost::numeric::ublas::norm_inf (inline) 97ms 2.40% 72.55% 123ms 3.04% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 72.55% 123ms 3.04% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 72.55% 123ms 3.04% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 0 0% 72.55% 106ms 2.62% VecDuplicate 0 0% 72.55% 89ms 2.20% VecTDot 88ms 2.18% 74.72% 89ms 2.20% exp_inline (inline) 20ms 0.49% 75.22% 80ms 1.98% MPI_Wtime 0 0% 75.22% 80ms 1.98% VecTDot_Seq 0 0% 75.22% 80ms 1.98% VecXDot_Seq_Private.constprop.0 3ms 0.074% 75.29% 77ms 1.90% VecNorm 0 0% 75.29% 75ms 1.86% VecMDot 3ms 0.074% 75.36% 75ms 1.86% VecMXDot_Private 0 0% 75.36% 64ms 1.58% VecNorm_Seq 13ms 0.32% 75.69% 63ms 1.56% PetscLogEventEndDefault 58ms 1.43% 77.12% 63ms 1.56% __ieee754_exp_fma 2ms 0.049% 77.17% 62ms 1.53% VecDuplicate_Seq 40ms 0.99% 78.16% 61ms 1.51% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 5ms 0.12% 78.28% 60ms 1.48% VecDestroy 2ms 0.049% 78.33% 60ms 1.48% __GI___clock_gettime 58ms 1.43% 79.77% 58ms 1.43% [[vdso]] 4ms 0.099% 79.87% 57ms 1.41% VecMAXPY 10ms 0.25% 80.11% 52ms 1.29% PetscLogEventBeginDefault 50ms 1.24% 81.35% 50ms 1.24% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 1ms 0.025% 81.38% 48ms 1.19% KSP_RemoveNullSpace (inline) 3ms 0.074% 81.45% 48ms 1.19% PetscMallocA 47ms 1.16% 82.61% 47ms 1.16% VecMDot_Seq 3ms 0.074% 82.69% 45ms 1.11% PetscMallocAlign (partial-inline) 3ms 0.074% 82.76% 44ms 1.09% VecDuplicate_MPI 4ms 0.099% 82.86% 40ms 0.99% VecCreate 16ms 0.4% 83.26% 37ms 0.92% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.26% 37ms 0.92% AbstractBidomainSolver::FinaliseLinearSystem 32ms 0.79% 84.05% 35ms 0.87% VecMAXPY_Seq 1ms 0.025% 84.07% 34ms 0.84% VecSetType 0 0% 84.07% 33ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 84.07% 33ms 0.82% PMPI_Init_thread 0 0% 84.07% 33ms 0.82% PetscInitialize 0 0% 84.07% 33ms 0.82% PetscSetup::setUpWorld 0 0% 84.07% 33ms 0.82% PetscSetupUtils::CommonSetup 0 0% 84.07% 33ms 0.82% PetscSetupUtils::InitialisePetsc 0 0% 84.07% 33ms 0.82% ompi_mpi_init 0 0% 84.07% 32ms 0.79% PetscMemcpy (inline) 31ms 0.77% 84.84% 31ms 0.77% [libchaste_heart.so] 3ms 0.074% 84.91% 30ms 0.74% VecCopy 7ms 0.17% 85.09% 30ms 0.74% __GI___libc_malloc 1ms 0.025% 85.11% 29ms 0.72% VecCreate_Seq 0 0% 85.11% 28ms 0.69% 2ms 0.049% 85.16% 27ms 0.67% VecCreate_MPI_Private 4ms 0.099% 85.26% 26ms 0.64% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 4ms 0.099% 85.36% 26ms 0.64% PetscHeaderDestroy_Private 0 0% 85.36% 26ms 0.64% PetscMemzero (inline) 3ms 0.074% 85.43% 26ms 0.64% __posix_memalign 26ms 0.64% 86.07% 26ms 0.64% log_inline (inline) 25ms 0.62% 86.69% 25ms 0.62% __memset_avx2_unaligned_erms 0 0% 86.69% 25ms 0.62% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 86.69% 25ms 0.62% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 86.69% 25ms 0.62% boost_numeric_ublas_abs (inline) 25ms 0.62% 87.31% 25ms 0.62% std::abs (inline) 6ms 0.15% 87.46% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 24ms 0.59% 88.05% 24ms 0.59% DistributedVector::Stripe::operator[] (inline) 24ms 0.59% 88.65% 24ms 0.59% __memcpy_avx_unaligned_erms 0 0% 88.65% 22ms 0.54% VecNorm_MPI 0 0% 88.65% 22ms 0.54% VecNorm_MPI_Default.constprop.0 16ms 0.4% 89.04% 22ms 0.54% _int_malloc 0 0% 89.04% 22ms 0.54% orte_init 0 0% 89.04% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 3ms 0.074% 89.12% 21ms 0.52% PetscOptionsGetViewer 2ms 0.049% 89.17% 21ms 0.52% VecSet