File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3545ms, 86.87% of 4081ms total Dropped 471 nodes (cum <= 20.41ms) flat flat% sum% cum cum% 0 0% 0% 4072ms 99.78% __libc_start_call_main 0 0% 0% 4072ms 99.78% __libc_start_main_impl 0 0% 0% 4072ms 99.78% _start 0 0% 0% 4069ms 99.71% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4069ms 99.71% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4069ms 99.71% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4069ms 99.71% main 0 0% 0% 4034ms 98.85% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4034ms 98.85% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4034ms 98.85% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 4027ms 98.68% AbstractCardiacProblem::Solve 3ms 0.074% 0.074% 4012ms 98.31% AbstractDynamicLinearPdeSolver::Solve 0 0% 0.074% 2079ms 50.94% LinearSystem::Solve 1ms 0.025% 0.098% 2033ms 49.82% KSPSolve 5ms 0.12% 0.22% 2033ms 49.82% KSPSolve_Private 2ms 0.049% 0.27% 1985ms 48.64% KSPSolve_CG 14ms 0.34% 0.61% 1615ms 39.57% AbstractCardiacTissue::SolveCellSystems 2ms 0.049% 0.66% 1290ms 31.61% PCApply 1ms 0.025% 0.69% 1284ms 31.46% PCApply_BJacobi_Singleblock 62ms 1.52% 2.21% 1098ms 26.91% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 0 0% 2.21% 1072ms 26.27% KSPSolve_PREONLY (partial-inline) 2ms 0.049% 2.25% 1010ms 24.75% KSP_PCApply.lto_priv.7 5ms 0.12% 2.38% 1009ms 24.72% MatSolve 0 0% 2.38% 1009ms 24.72% PCApply_ICC 992ms 24.31% 26.68% 997ms 24.43% MatSolve_SeqSBAIJ_1_NaturalOrdering 201ms 4.93% 31.61% 953ms 23.35% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 4ms 0.098% 31.71% 561ms 13.75% MatMult 4ms 0.098% 31.81% 549ms 13.45% MatMult_SeqAIJ 534ms 13.09% 44.89% 548ms 13.43% MatMult_SeqAIJ_Inode (inline) 12ms 0.29% 45.19% 462ms 11.32% AbstractCardiacTissue::UpdateCaches (inline) 77ms 1.89% 47.07% 445ms 10.90% CardiacNewtonSolver::Solve 127ms 3.11% 50.18% 431ms 10.56% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 4ms 0.098% 50.28% 391ms 9.58% KSPConvergedDefault 0 0% 50.28% 384ms 9.41% KSP_PCApply (partial-inline) 51ms 1.25% 51.53% 269ms 6.59% BidomainSolver::SetupLinearSystem 136ms 3.33% 54.86% 243ms 5.95% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 26ms 0.64% 55.50% 226ms 5.54% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 32ms 0.78% 56.29% 200ms 4.90% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 56.29% 198ms 4.85% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 42ms 1.03% 57.31% 198ms 4.85% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 2ms 0.049% 57.36% 173ms 4.24% MatNullSpaceRemove 158ms 3.87% 61.23% 158ms 3.87% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 61.26% 137ms 3.36% std::pow (inline) 19ms 0.47% 61.73% 136ms 3.33% __pow 70ms 1.72% 63.44% 129ms 3.16% __GI___exp 0 0% 63.44% 121ms 2.96% VecDuplicate 0 0% 63.44% 119ms 2.92% boost::numeric::ublas::norm_inf (inline) 75ms 1.84% 65.28% 119ms 2.92% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 65.28% 119ms 2.92% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 65.28% 119ms 2.92% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 23ms 0.56% 65.84% 117ms 2.87% __ieee754_pow_fma 117ms 2.87% 68.71% 117ms 2.87% ddot_ 107ms 2.62% 71.33% 110ms 2.70% __ieee754_log_fma 0 0% 71.33% 102ms 2.50% VecMDot 7ms 0.17% 71.50% 102ms 2.50% VecMXDot_Private 2ms 0.049% 71.55% 81ms 1.98% VecTDot 44ms 1.08% 72.63% 80ms 1.96% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 28ms 0.69% 73.32% 80ms 1.96% MPI_Wtime 1ms 0.025% 73.34% 77ms 1.89% VecNorm 4ms 0.098% 73.44% 74ms 1.81% VecDuplicate_Seq 6ms 0.15% 73.58% 69ms 1.69% VecMAXPY 14ms 0.34% 73.93% 68ms 1.67% PetscLogEventEndDefault 0 0% 73.93% 68ms 1.67% VecTDot_Seq 0 0% 73.93% 68ms 1.67% VecXDot_Seq_Private.constprop.0 62ms 1.52% 75.45% 66ms 1.62% VecMDot_Seq 64ms 1.57% 77.02% 66ms 1.62% exp_inline (inline) 0 0% 77.02% 63ms 1.54% VecNorm_Seq 0 0% 77.02% 59ms 1.45% KSP_RemoveNullSpace (inline) 6ms 0.15% 77.16% 59ms 1.45% PetscMallocA 56ms 1.37% 78.53% 59ms 1.45% __ieee754_exp_fma 11ms 0.27% 78.80% 58ms 1.42% PetscLogEventBeginDefault 0 0% 78.80% 58ms 1.42% VecCreate 1ms 0.025% 78.83% 56ms 1.37% VecDestroy 2ms 0.049% 78.88% 53ms 1.30% PetscMallocAlign (partial-inline) 1ms 0.025% 78.90% 52ms 1.27% __GI___clock_gettime 51ms 1.25% 80.15% 51ms 1.25% [[vdso]] 1ms 0.025% 80.18% 47ms 1.15% VecDuplicate_MPI 14ms 0.34% 80.52% 42ms 1.03% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 80.52% 42ms 1.03% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 80.52% 41ms 1.00% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 80.52% 41ms 1.00% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 80.52% 41ms 1.00% boost_numeric_ublas_abs (inline) 41ms 1.00% 81.52% 41ms 1.00% std::abs (inline) 37ms 0.91% 82.43% 40ms 0.98% VecMAXPY_Seq 6ms 0.15% 82.58% 36ms 0.88% __GI___libc_malloc 3ms 0.074% 82.65% 36ms 0.88% __posix_memalign 4ms 0.098% 82.75% 33ms 0.81% VecCopy 0 0% 82.75% 31ms 0.76% CxxTest::RealWorldDescription::setUp 0 0% 82.75% 31ms 0.76% PMPI_Init_thread 0 0% 82.75% 31ms 0.76% PetscInitialize 0 0% 82.75% 31ms 0.76% PetscSetup::setUpWorld 0 0% 82.75% 31ms 0.76% PetscSetupUtils::CommonSetup 0 0% 82.75% 31ms 0.76% PetscSetupUtils::InitialisePetsc 0 0% 82.75% 31ms 0.76% ompi_mpi_init 7ms 0.17% 82.92% 30ms 0.74% VecSetType 21ms 0.51% 83.44% 30ms 0.74% _int_malloc 29ms 0.71% 84.15% 29ms 0.71% DistributedVector::Iterator::operator!= 0 0% 84.15% 28ms 0.69% VecNorm_MPI 0 0% 84.15% 28ms 0.69% VecNorm_MPI_Default.constprop.0 27ms 0.66% 84.81% 27ms 0.66% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 3ms 0.074% 84.88% 27ms 0.66% PetscHeaderDestroy_Private 27ms 0.66% 85.54% 27ms 0.66% [libchaste_heart.so] 26ms 0.64% 86.18% 26ms 0.64% log_inline (inline) 0 0% 86.18% 25ms 0.61% 8ms 0.2% 86.38% 25ms 0.61% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 4ms 0.098% 86.47% 25ms 0.61% VecAXPY 1ms 0.025% 86.50% 24ms 0.59% VecCreate_MPI_Private 6ms 0.15% 86.65% 23ms 0.56% AbstractCardiacCell::SetVoltage 3ms 0.074% 86.72% 23ms 0.56% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 6ms 0.15% 86.87% 23ms 0.56% PetscHeaderCreate_Private 0 0% 86.87% 23ms 0.56% PetscMemzero (inline) 0 0% 86.87% 22ms 0.54% orte_init