File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3497ms, 86.82% of 4028ms total Dropped 457 nodes (cum <= 20.14ms) flat flat% sum% cum cum% 0 0% 0% 4022ms 99.85% __libc_start_call_main 0 0% 0% 4022ms 99.85% __libc_start_main_impl 0 0% 0% 4022ms 99.85% _start 0 0% 0% 4019ms 99.78% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4019ms 99.78% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4019ms 99.78% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4019ms 99.78% main 0 0% 0% 3985ms 98.93% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3985ms 98.93% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3985ms 98.93% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 3978ms 98.76% AbstractCardiacProblem::Solve 2ms 0.05% 0.05% 3965ms 98.44% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.05% 1974ms 49.01% LinearSystem::Solve 0 0% 0.05% 1932ms 47.96% KSPSolve 8ms 0.2% 0.25% 1932ms 47.96% KSPSolve_Private 2ms 0.05% 0.3% 1895ms 47.05% KSPSolve_CG 7ms 0.17% 0.47% 1669ms 41.43% AbstractCardiacTissue::SolveCellSystems 4ms 0.099% 0.57% 1216ms 30.19% PCApply 0 0% 0.57% 1201ms 29.82% PCApply_BJacobi_Singleblock 56ms 1.39% 1.96% 1098ms 27.26% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 1.96% 991ms 24.60% KSPSolve_PREONLY (partial-inline) 0 0% 1.96% 971ms 24.11% KSP_PCApply.lto_priv.7 223ms 5.54% 7.50% 965ms 23.96% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 2ms 0.05% 7.55% 927ms 23.01% PCApply_ICC 2ms 0.05% 7.60% 925ms 22.96% MatSolve 909ms 22.57% 30.16% 921ms 22.86% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 16ms 0.4% 30.56% 540ms 13.41% AbstractCardiacTissue::UpdateCaches (inline) 2ms 0.05% 30.61% 536ms 13.31% MatMult 2ms 0.05% 30.66% 528ms 13.11% MatMult_SeqAIJ 517ms 12.84% 43.50% 528ms 13.11% MatMult_SeqAIJ_Inode (inline) 137ms 3.40% 46.90% 507ms 12.59% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 48ms 1.19% 48.09% 445ms 11.05% CardiacNewtonSolver::Solve 0 0% 48.09% 347ms 8.61% KSP_PCApply (partial-inline) 4ms 0.099% 48.19% 345ms 8.57% KSPConvergedDefault 62ms 1.54% 49.73% 286ms 7.10% BidomainSolver::SetupLinearSystem 129ms 3.20% 52.93% 240ms 5.96% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 31ms 0.77% 53.70% 219ms 5.44% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 15ms 0.37% 54.07% 209ms 5.19% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 54.07% 193ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 44ms 1.09% 55.16% 193ms 4.79% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 180ms 4.47% 59.63% 180ms 4.47% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 3ms 0.074% 59.71% 160ms 3.97% MatNullSpaceRemove 4ms 0.099% 59.81% 159ms 3.95% std::pow (inline) 13ms 0.32% 60.13% 155ms 3.85% __pow 0 0% 60.13% 144ms 3.57% boost::numeric::ublas::norm_inf (inline) 101ms 2.51% 62.64% 144ms 3.57% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 62.64% 144ms 3.57% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 62.64% 144ms 3.57% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 17ms 0.42% 63.06% 142ms 3.53% __ieee754_pow_fma 134ms 3.33% 66.39% 136ms 3.38% __ieee754_log_fma 67ms 1.66% 68.05% 129ms 3.20% __GI___exp 0 0% 68.05% 125ms 3.10% VecDuplicate 110ms 2.73% 70.78% 110ms 2.73% ddot_ 1ms 0.025% 70.80% 90ms 2.23% VecMDot 4ms 0.099% 70.90% 89ms 2.21% VecMXDot_Private 21ms 0.52% 71.43% 83ms 2.06% MPI_Wtime 1ms 0.025% 71.45% 80ms 1.99% VecTDot 72ms 1.79% 73.24% 78ms 1.94% exp_inline (inline) 47ms 1.17% 74.40% 73ms 1.81% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 2ms 0.05% 74.45% 69ms 1.71% VecDuplicate_Seq 3ms 0.074% 74.53% 67ms 1.66% VecMAXPY 0 0% 74.53% 67ms 1.66% VecTDot_Seq 0 0% 74.53% 67ms 1.66% VecXDot_Seq_Private.constprop.0 2ms 0.05% 74.58% 65ms 1.61% VecNorm 8ms 0.2% 74.78% 64ms 1.59% PetscLogEventBeginDefault 16ms 0.4% 75.17% 63ms 1.56% PetscLogEventEndDefault 4ms 0.099% 75.27% 63ms 1.56% VecDestroy 62ms 1.54% 76.81% 63ms 1.56% VecMDot_Seq 62ms 1.54% 78.35% 62ms 1.54% [[vdso]] 0 0% 78.35% 62ms 1.54% __GI___clock_gettime 57ms 1.42% 79.77% 60ms 1.49% __ieee754_exp_fma 1ms 0.025% 79.79% 56ms 1.39% VecDuplicate_MPI 1ms 0.025% 79.82% 55ms 1.37% KSP_RemoveNullSpace (inline) 4ms 0.099% 79.92% 55ms 1.37% VecCreate 0 0% 79.92% 51ms 1.27% VecNorm_Seq 5ms 0.12% 80.04% 47ms 1.17% PetscMallocA 45ms 1.12% 81.16% 45ms 1.12% log_inline (inline) 7ms 0.17% 81.33% 42ms 1.04% PetscMallocAlign 0 0% 81.33% 41ms 1.02% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 81.33% 41ms 1.02% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 81.33% 41ms 1.02% boost_numeric_ublas_abs (inline) 41ms 1.02% 82.35% 41ms 1.02% std::abs (inline) 37ms 0.92% 83.27% 38ms 0.94% VecMAXPY_Seq 36ms 0.89% 84.16% 36ms 0.89% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 84.16% 36ms 0.89% VecSetType 2ms 0.05% 84.21% 34ms 0.84% PetscHeaderDestroy_Private 31ms 0.77% 84.98% 31ms 0.77% [libchaste_heart.so] 0 0% 84.98% 30ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 84.98% 30ms 0.74% PMPI_Init_thread 0 0% 84.98% 30ms 0.74% PetscInitialize 0 0% 84.98% 30ms 0.74% PetscSetup::setUpWorld 0 0% 84.98% 30ms 0.74% PetscSetupUtils::CommonSetup 0 0% 84.98% 30ms 0.74% PetscSetupUtils::InitialisePetsc 4ms 0.099% 85.08% 30ms 0.74% VecCopy 0 0% 85.08% 30ms 0.74% ompi_mpi_init 10ms 0.25% 85.33% 26ms 0.65% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 85.33% 26ms 0.65% AbstractBidomainSolver::FinaliseLinearSystem 26ms 0.65% 85.97% 26ms 0.65% DistributedVector::Stripe::operator[] (inline) 0 0% 85.97% 26ms 0.65% PetscMemcpy (inline) 9ms 0.22% 86.20% 25ms 0.62% __GI___libc_free 4ms 0.099% 86.30% 24ms 0.6% PetscFreeAlign 0 0% 86.30% 24ms 0.6% VecCreate_Seq 1ms 0.025% 86.32% 23ms 0.57% VecAXPY 2ms 0.05% 86.37% 23ms 0.57% __posix_memalign 0 0% 86.37% 22ms 0.55% 1ms 0.025% 86.40% 22ms 0.55% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 5ms 0.12% 86.52% 22ms 0.55% VecCreate_MPI_Private 0 0% 86.52% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 2ms 0.05% 86.57% 21ms 0.52% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 6ms 0.15% 86.72% 21ms 0.52% PetscHeaderCreate_Private 4ms 0.099% 86.82% 21ms 0.52% __GI___libc_malloc 0 0% 86.82% 21ms 0.52% orte_init