File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3540ms, 86.98% of 4070ms total Dropped 479 nodes (cum <= 20.35ms) flat flat% sum% cum cum% 0 0% 0% 4062ms 99.80% __libc_start_call_main 0 0% 0% 4062ms 99.80% __libc_start_main_impl 0 0% 0% 4062ms 99.80% _start 0 0% 0% 4059ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4059ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4059ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4059ms 99.73% main 0 0% 0% 4020ms 98.77% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4020ms 98.77% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4020ms 98.77% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.025% 0.025% 4012ms 98.57% AbstractCardiacProblem::Solve 0 0% 0.025% 3996ms 98.18% AbstractDynamicLinearPdeSolver::Solve 1ms 0.025% 0.049% 2045ms 50.25% LinearSystem::Solve 0 0% 0.049% 1997ms 49.07% KSPSolve 10ms 0.25% 0.29% 1997ms 49.07% KSPSolve_Private 2ms 0.049% 0.34% 1959ms 48.13% KSPSolve_CG 4ms 0.098% 0.44% 1663ms 40.86% AbstractCardiacTissue::SolveCellSystems 1ms 0.025% 0.47% 1257ms 30.88% PCApply 0 0% 0.47% 1244ms 30.57% PCApply_BJacobi_Singleblock 74ms 1.82% 2.29% 1110ms 27.27% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 2ms 0.049% 2.33% 1048ms 25.75% KSPSolve_PREONLY (partial-inline) 0 0% 2.33% 1001ms 24.59% KSP_PCApply.lto_priv.7 4ms 0.098% 2.43% 982ms 24.13% MatSolve 0 0% 2.43% 982ms 24.13% PCApply_ICC 963ms 23.66% 26.09% 973ms 23.91% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 198ms 4.86% 30.96% 970ms 23.83% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 4ms 0.098% 31.06% 559ms 13.73% MatMult 5ms 0.12% 31.18% 548ms 13.46% MatMult_SeqAIJ (partial-inline) 535ms 13.14% 44.32% 548ms 13.46% MatMult_SeqAIJ_Inode (inline) 12ms 0.29% 44.62% 514ms 12.63% AbstractCardiacTissue::UpdateCaches (inline) 145ms 3.56% 48.18% 485ms 11.92% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 48ms 1.18% 49.36% 441ms 10.84% CardiacNewtonSolver::Solve 4ms 0.098% 49.46% 368ms 9.04% KSPConvergedDefault 0 0% 49.46% 355ms 8.72% KSP_PCApply (partial-inline) 54ms 1.33% 50.79% 251ms 6.17% BidomainSolver::SetupLinearSystem 128ms 3.14% 53.93% 249ms 6.12% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 23ms 0.57% 54.50% 225ms 5.53% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 39ms 0.96% 55.45% 219ms 5.38% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 0 0% 55.45% 201ms 4.94% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 41ms 1.01% 56.46% 201ms 4.94% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 171ms 4.20% 60.66% 171ms 4.20% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 1ms 0.025% 60.69% 155ms 3.81% MatNullSpaceRemove 1ms 0.025% 60.71% 154ms 3.78% std::pow (inline) 20ms 0.49% 61.20% 153ms 3.76% __pow 144ms 3.54% 64.74% 144ms 3.54% __ieee754_log_fma 0 0% 64.74% 135ms 3.32% boost::numeric::ublas::norm_inf (inline) 98ms 2.41% 67.15% 135ms 3.32% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 67.15% 135ms 3.32% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 67.15% 135ms 3.32% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 24ms 0.59% 67.74% 133ms 3.27% __ieee754_pow_fma 69ms 1.70% 69.43% 129ms 3.17% __GI___exp 0 0% 69.43% 119ms 2.92% VecDuplicate 114ms 2.80% 72.24% 114ms 2.80% ddot_ 0 0% 72.24% 88ms 2.16% VecMDot 2ms 0.049% 72.29% 88ms 2.16% VecMXDot_Private 4ms 0.098% 72.38% 86ms 2.11% VecNorm 25ms 0.61% 73.00% 81ms 1.99% MPI_Wtime 75ms 1.84% 74.84% 78ms 1.92% exp_inline (inline) 1ms 0.025% 74.86% 67ms 1.65% VecNorm_Seq 6ms 0.15% 75.01% 66ms 1.62% VecMAXPY 62ms 1.52% 76.54% 66ms 1.62% VecMDot_Seq 1ms 0.025% 76.56% 66ms 1.62% VecTDot 39ms 0.96% 77.52% 63ms 1.55% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 1ms 0.025% 77.54% 61ms 1.50% VecDestroy 0 0% 77.54% 60ms 1.47% VecDuplicate_Seq 12ms 0.29% 77.84% 59ms 1.45% PetscLogEventEndDefault 5ms 0.12% 77.96% 59ms 1.45% VecDuplicate_MPI 0 0% 77.96% 58ms 1.43% VecTDot_Seq 0 0% 77.96% 58ms 1.43% VecXDot_Seq_Private.constprop.0 56ms 1.38% 79.34% 58ms 1.43% __ieee754_exp_fma 56ms 1.38% 80.71% 56ms 1.38% [[vdso]] 0 0% 80.71% 56ms 1.38% __GI___clock_gettime 1ms 0.025% 80.74% 53ms 1.30% VecCreate 6ms 0.15% 80.88% 51ms 1.25% PetscMallocA 9ms 0.22% 81.11% 50ms 1.23% PetscLogEventBeginDefault 1ms 0.025% 81.13% 45ms 1.11% PetscMallocAlign 34ms 0.84% 81.97% 37ms 0.91% VecMAXPY_Seq 36ms 0.88% 82.85% 36ms 0.88% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 0 0% 82.85% 35ms 0.86% CxxTest::RealWorldDescription::setUp 0 0% 82.85% 35ms 0.86% PetscSetup::setUpWorld 0 0% 82.85% 35ms 0.86% PetscSetupUtils::CommonSetup 0 0% 82.85% 35ms 0.86% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 82.85% 35ms 0.86% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 82.85% 35ms 0.86% boost_numeric_ublas_abs (inline) 35ms 0.86% 83.71% 35ms 0.86% std::abs (inline) 0 0% 83.71% 34ms 0.84% PMPI_Init_thread 0 0% 83.71% 34ms 0.84% PetscInitialize 0 0% 83.71% 34ms 0.84% PetscSetupUtils::InitialisePetsc 0 0% 83.71% 34ms 0.84% ompi_mpi_init 2ms 0.049% 83.76% 33ms 0.81% PetscHeaderDestroy_Private 2ms 0.049% 83.81% 32ms 0.79% VecSetType 3ms 0.074% 83.88% 31ms 0.76% VecCopy 10ms 0.25% 84.13% 31ms 0.76% __GI___libc_malloc 0 0% 84.13% 31ms 0.76% __posix_memalign 6ms 0.15% 84.28% 30ms 0.74% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.28% 30ms 0.74% AbstractBidomainSolver::FinaliseLinearSystem 30ms 0.74% 85.01% 30ms 0.74% log_inline (inline) 0 0% 85.01% 29ms 0.71% VecNorm_MPI 1ms 0.025% 85.04% 29ms 0.71% VecNorm_MPI_Default.constprop.0 3ms 0.074% 85.11% 28ms 0.69% VecCreate_MPI_Private 28ms 0.69% 85.80% 28ms 0.69% [libchaste_heart.so] 0 0% 85.80% 26ms 0.64% 0 0% 85.80% 26ms 0.64% PetscMemcpy (inline) 2ms 0.049% 85.85% 26ms 0.64% VecAXPY 1ms 0.025% 85.87% 25ms 0.61% PetscHeaderCreate_Private 1ms 0.025% 85.90% 25ms 0.61% VecCreate_Seq 0 0% 85.90% 24ms 0.59% orte_init 5ms 0.12% 86.02% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 7ms 0.17% 86.19% 23ms 0.57% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 2ms 0.049% 86.24% 23ms 0.57% PMPI_Comm_get_attr 18ms 0.44% 86.68% 22ms 0.54% _int_malloc 0 0% 86.68% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 12ms 0.29% 86.98% 21ms 0.52% ompi_attr_get_c