File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3570ms, 87.03% of 4102ms total Dropped 492 nodes (cum <= 20.51ms) flat flat% sum% cum cum% 0 0% 0% 4093ms 99.78% __libc_start_call_main 0 0% 0% 4093ms 99.78% __libc_start_main_impl 0 0% 0% 4093ms 99.78% _start 0 0% 0% 4090ms 99.71% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4090ms 99.71% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4090ms 99.71% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4090ms 99.71% main 0 0% 0% 4048ms 98.68% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4048ms 98.68% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4048ms 98.68% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 2ms 0.049% 0.049% 4040ms 98.49% AbstractCardiacProblem::Solve 1ms 0.024% 0.073% 4020ms 98.00% AbstractDynamicLinearPdeSolver::Solve 1ms 0.024% 0.098% 2072ms 50.51% LinearSystem::Solve 0 0% 0.098% 2030ms 49.49% KSPSolve 4ms 0.098% 0.2% 2030ms 49.49% KSPSolve_Private 0 0% 0.2% 1982ms 48.32% KSPSolve_CG 9ms 0.22% 0.41% 1656ms 40.37% AbstractCardiacTissue::SolveCellSystems 1ms 0.024% 0.44% 1304ms 31.79% PCApply 1ms 0.024% 0.46% 1293ms 31.52% PCApply_BJacobi_Singleblock 67ms 1.63% 2.10% 1117ms 27.23% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.10% 1099ms 26.79% KSPSolve_PREONLY (partial-inline) 3ms 0.073% 2.17% 1033ms 25.18% MatSolve 0 0% 2.17% 1033ms 25.18% PCApply_ICC 1019ms 24.84% 27.01% 1025ms 24.99% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 1ms 0.024% 27.04% 1015ms 24.74% KSP_PCApply.lto_priv.7 206ms 5.02% 32.06% 962ms 23.45% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 8ms 0.2% 32.25% 534ms 13.02% MatMult 29ms 0.71% 32.96% 521ms 12.70% MatMult_SeqAIJ (partial-inline) 12ms 0.29% 33.25% 503ms 12.26% AbstractCardiacTissue::UpdateCaches (inline) 486ms 11.85% 45.10% 498ms 12.14% MatMult_SeqAIJ_Inode (inline) 137ms 3.34% 48.44% 471ms 11.48% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 56ms 1.37% 49.80% 427ms 10.41% CardiacNewtonSolver::Solve 1ms 0.024% 49.83% 392ms 9.56% KSPConvergedDefault 0 0% 49.83% 386ms 9.41% KSP_PCApply (partial-inline) 145ms 3.53% 53.36% 261ms 6.36% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 48ms 1.17% 54.53% 250ms 6.09% BidomainSolver::SetupLinearSystem 27ms 0.66% 55.19% 238ms 5.80% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 35ms 0.85% 56.05% 219ms 5.34% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 56.05% 209ms 5.10% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 45ms 1.10% 57.14% 209ms 5.10% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 2ms 0.049% 57.19% 176ms 4.29% MatNullSpaceRemove 168ms 4.10% 61.29% 168ms 4.10% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 16ms 0.39% 61.68% 154ms 3.75% __pow 0 0% 61.68% 154ms 3.75% std::pow (inline) 66ms 1.61% 63.29% 138ms 3.36% __GI___exp 26ms 0.63% 63.92% 138ms 3.36% __ieee754_pow_fma 133ms 3.24% 67.16% 136ms 3.32% __ieee754_log_fma 118ms 2.88% 70.04% 118ms 2.88% ddot_ 1ms 0.024% 70.06% 113ms 2.75% VecMDot 8ms 0.2% 70.26% 112ms 2.73% VecMXDot_Private 0 0% 70.26% 107ms 2.61% VecDuplicate 0 0% 70.26% 101ms 2.46% boost::numeric::ublas::norm_inf (inline) 63ms 1.54% 71.79% 101ms 2.46% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.79% 101ms 2.46% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.79% 101ms 2.46% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 4ms 0.098% 71.89% 85ms 2.07% VecTDot 49ms 1.19% 73.09% 84ms 2.05% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 71ms 1.73% 74.82% 75ms 1.83% exp_inline (inline) 0 0% 74.82% 73ms 1.78% VecTDot_Seq 0 0% 74.82% 73ms 1.78% VecXDot_Seq_Private.constprop.0 70ms 1.71% 76.52% 72ms 1.76% VecMDot_Seq 68ms 1.66% 78.18% 71ms 1.73% __ieee754_exp_fma 3ms 0.073% 78.25% 66ms 1.61% VecNorm 16ms 0.39% 78.64% 64ms 1.56% MPI_Wtime 1ms 0.024% 78.67% 61ms 1.49% VecMAXPY 0 0% 78.67% 59ms 1.44% KSP_RemoveNullSpace (inline) 2ms 0.049% 78.72% 59ms 1.44% VecDestroy 10ms 0.24% 78.96% 56ms 1.37% PetscLogEventBeginDefault 4ms 0.098% 79.06% 56ms 1.37% VecDuplicate_MPI 0 0% 79.06% 55ms 1.34% VecNorm_Seq 12ms 0.29% 79.35% 51ms 1.24% PetscLogEventEndDefault 2ms 0.049% 79.40% 51ms 1.24% VecDuplicate_Seq 4ms 0.098% 79.50% 48ms 1.17% __GI___clock_gettime 4ms 0.098% 79.60% 45ms 1.10% PetscMallocA 44ms 1.07% 80.67% 44ms 1.07% [[vdso]] 1ms 0.024% 80.69% 41ms 1% PetscMallocAlign 1ms 0.024% 80.72% 41ms 1% VecCreate 38ms 0.93% 81.64% 41ms 1% VecMAXPY_Seq 0 0% 81.64% 38ms 0.93% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 81.64% 38ms 0.93% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 81.64% 38ms 0.93% boost_numeric_ublas_abs (inline) 38ms 0.93% 82.57% 38ms 0.93% std::abs (inline) 0 0% 82.57% 37ms 0.9% CxxTest::RealWorldDescription::setUp 0 0% 82.57% 37ms 0.9% PMPI_Init_thread 0 0% 82.57% 37ms 0.9% PetscInitialize 0 0% 82.57% 37ms 0.9% PetscSetup::setUpWorld 0 0% 82.57% 37ms 0.9% PetscSetupUtils::CommonSetup 0 0% 82.57% 37ms 0.9% PetscSetupUtils::InitialisePetsc 0 0% 82.57% 37ms 0.9% ompi_mpi_init 6ms 0.15% 82.72% 35ms 0.85% VecCopy 35ms 0.85% 83.57% 35ms 0.85% log_inline (inline) 0 0% 83.57% 34ms 0.83% AbstractBidomainSolver::FinaliseLinearSystem 9ms 0.22% 83.79% 33ms 0.8% AbstractBidomainSolver::CheckCompatibilityCondition 1ms 0.024% 83.81% 33ms 0.8% VecCreate_MPI_Private 0 0% 83.81% 31ms 0.76% PetscMemcpy (inline) 1ms 0.024% 83.84% 30ms 0.73% 30ms 0.73% 84.57% 30ms 0.73% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 30ms 0.73% 85.30% 30ms 0.73% [libchaste_heart.so] 7ms 0.17% 85.47% 28ms 0.68% PetscHeaderDestroy_Private 3ms 0.073% 85.54% 26ms 0.63% VecSetType 4ms 0.098% 85.64% 26ms 0.63% __posix_memalign 0 0% 85.64% 26ms 0.63% orte_init 8ms 0.2% 85.84% 25ms 0.61% __GI___libc_malloc 4ms 0.098% 85.93% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 24ms 0.59% 86.52% 24ms 0.59% __memcpy_avx_unaligned_erms 6ms 0.15% 86.67% 22ms 0.54% PetscOptionsFindPair 0 0% 86.67% 22ms 0.54% VecAXPY 0 0% 86.67% 22ms 0.54% VecNorm_MPI 1ms 0.024% 86.69% 22ms 0.54% VecNorm_MPI_Default.constprop.0 14ms 0.34% 87.03% 22ms 0.54% std::__cxx11::basic_string::basic_string 0 0% 87.03% 21ms 0.51% AbstractFeVolumeIntegralAssembler::DoAssemble