File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3560ms, 87.75% of 4057ms total Dropped 463 nodes (cum <= 20.29ms) flat flat% sum% cum cum% 0 0% 0% 4048ms 99.78% __libc_start_call_main 0 0% 0% 4048ms 99.78% __libc_start_main_impl 0 0% 0% 4048ms 99.78% _start 0 0% 0% 4046ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4046ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4046ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4046ms 99.73% main 0 0% 0% 4015ms 98.96% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4015ms 98.96% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4015ms 98.96% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 0 0% 0% 4007ms 98.77% AbstractCardiacProblem::Solve 0 0% 0% 3996ms 98.50% AbstractDynamicLinearPdeSolver::Solve 0 0% 0% 2041ms 50.31% LinearSystem::Solve 0 0% 0% 2006ms 49.45% KSPSolve 9ms 0.22% 0.22% 2006ms 49.45% KSPSolve_Private 2ms 0.049% 0.27% 1955ms 48.19% KSPSolve_CG 9ms 0.22% 0.49% 1644ms 40.52% AbstractCardiacTissue::SolveCellSystems 3ms 0.074% 0.57% 1276ms 31.45% PCApply 1ms 0.025% 0.59% 1265ms 31.18% PCApply_BJacobi_Singleblock 66ms 1.63% 2.22% 1103ms 27.19% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 1ms 0.025% 2.24% 1081ms 26.65% KSPSolve_PREONLY (partial-inline) 3ms 0.074% 2.32% 1023ms 25.22% MatSolve 0 0% 2.32% 1023ms 25.22% PCApply_ICC 1003ms 24.72% 27.04% 1013ms 24.97% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 27.04% 1002ms 24.70% KSP_PCApply.lto_priv.7 191ms 4.71% 31.75% 969ms 23.88% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 2ms 0.049% 31.80% 523ms 12.89% MatMult 6ms 0.15% 31.94% 513ms 12.64% MatMult_SeqAIJ (partial-inline) 503ms 12.40% 44.34% 512ms 12.62% MatMult_SeqAIJ_Inode (inline) 10ms 0.25% 44.59% 487ms 12.00% AbstractCardiacTissue::UpdateCaches (inline) 113ms 2.79% 47.37% 447ms 11.02% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 49ms 1.21% 48.58% 424ms 10.45% CardiacNewtonSolver::Solve 3ms 0.074% 48.66% 381ms 9.39% KSPConvergedDefault 0 0% 48.66% 375ms 9.24% KSP_PCApply (partial-inline) 38ms 0.94% 49.59% 287ms 7.07% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 56ms 1.38% 50.97% 265ms 6.53% BidomainSolver::SetupLinearSystem 138ms 3.40% 54.38% 253ms 6.24% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 238ms 5.87% 60.24% 238ms 5.87% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 26ms 0.64% 60.88% 222ms 5.47% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 0 0% 60.88% 193ms 4.76% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 35ms 0.86% 61.75% 193ms 4.76% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 2ms 0.049% 61.79% 152ms 3.75% MatNullSpaceRemove 138ms 3.40% 65.20% 140ms 3.45% __ieee754_log_fma 133ms 3.28% 68.47% 133ms 3.28% ddot_ 60ms 1.48% 69.95% 129ms 3.18% __GI___exp 1ms 0.025% 69.98% 118ms 2.91% VecDuplicate 1ms 0.025% 70.00% 106ms 2.61% std::pow (inline) 0 0% 70.00% 105ms 2.59% boost::numeric::ublas::norm_inf (inline) 74ms 1.82% 71.83% 105ms 2.59% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 71.83% 105ms 2.59% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 71.83% 105ms 2.59% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 11ms 0.27% 72.10% 103ms 2.54% __pow 0 0% 72.10% 93ms 2.29% VecMDot 2ms 0.049% 72.15% 93ms 2.29% VecMXDot_Private 14ms 0.35% 72.49% 92ms 2.27% __ieee754_pow_fma 26ms 0.64% 73.13% 91ms 2.24% MPI_Wtime 0 0% 73.13% 83ms 2.05% VecTDot 1ms 0.025% 73.16% 80ms 1.97% VecNorm 0 0% 73.16% 72ms 1.77% VecTDot_Seq 0 0% 73.16% 72ms 1.77% VecXDot_Seq_Private.constprop.0 63ms 1.55% 74.71% 68ms 1.68% __ieee754_exp_fma 65ms 1.60% 76.31% 67ms 1.65% VecMDot_Seq 15ms 0.37% 76.68% 66ms 1.63% PetscLogEventEndDefault 0 0% 76.68% 66ms 1.63% VecNorm_Seq 65ms 1.60% 78.28% 65ms 1.60% [[vdso]] 0 0% 78.28% 65ms 1.60% __GI___clock_gettime 36ms 0.89% 79.17% 63ms 1.55% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 2ms 0.049% 79.22% 62ms 1.53% VecDuplicate_Seq 10ms 0.25% 79.47% 60ms 1.48% PetscLogEventBeginDefault 2ms 0.049% 79.52% 58ms 1.43% VecDestroy 1ms 0.025% 79.54% 57ms 1.40% VecMAXPY 56ms 1.38% 80.92% 56ms 1.38% exp_inline (inline) 1ms 0.025% 80.95% 55ms 1.36% VecDuplicate_MPI 4ms 0.099% 81.05% 47ms 1.16% PetscMallocA 3ms 0.074% 81.12% 46ms 1.13% VecCreate 2ms 0.049% 81.17% 43ms 1.06% PetscMallocAlign (partial-inline) 6ms 0.15% 81.32% 42ms 1.04% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.32% 42ms 1.04% AbstractBidomainSolver::FinaliseLinearSystem 41ms 1.01% 82.33% 41ms 1.01% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 37ms 0.91% 83.24% 37ms 0.91% [libchaste_heart.so] 8ms 0.2% 83.44% 36ms 0.89% VecCreate_MPI_Private 34ms 0.84% 84.27% 34ms 0.84% DistributedVector::Iterator::operator!= 31ms 0.76% 85.04% 34ms 0.84% VecMAXPY_Seq 4ms 0.099% 85.14% 31ms 0.76% PetscHeaderDestroy_Private 1ms 0.025% 85.16% 31ms 0.76% VecSetType 0 0% 85.16% 29ms 0.71% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 85.16% 29ms 0.71% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 85.16% 29ms 0.71% boost_numeric_ublas_abs (inline) 29ms 0.71% 85.88% 29ms 0.71% std::abs (inline) 0 0% 85.88% 27ms 0.67% CxxTest::RealWorldDescription::setUp 0 0% 85.88% 27ms 0.67% PMPI_Init_thread 0 0% 85.88% 27ms 0.67% PetscInitialize 0 0% 85.88% 27ms 0.67% PetscMemzero (inline) 0 0% 85.88% 27ms 0.67% PetscSetup::setUpWorld 0 0% 85.88% 27ms 0.67% PetscSetupUtils::CommonSetup 0 0% 85.88% 27ms 0.67% PetscSetupUtils::InitialisePetsc 0 0% 85.88% 27ms 0.67% ompi_mpi_init 0 0% 85.88% 26ms 0.64% PetscMemcpy (inline) 26ms 0.64% 86.52% 26ms 0.64% __memset_avx2_unaligned_erms 8ms 0.2% 86.71% 25ms 0.62% AbstractCardiacCell::SetVoltage 3ms 0.074% 86.79% 25ms 0.62% VecCopy 8ms 0.2% 86.99% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 6ms 0.15% 87.13% 24ms 0.59% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 0 0% 87.13% 24ms 0.59% VecNorm_MPI 0 0% 87.13% 24ms 0.59% VecNorm_MPI_Default.constprop.0 1ms 0.025% 87.16% 23ms 0.57% KSP_RemoveNullSpace (inline) 0 0% 87.16% 23ms 0.57% VecCreate_Seq 0 0% 87.16% 22ms 0.54% 2ms 0.049% 87.21% 22ms 0.54% PetscHeaderCreate_Private 1ms 0.025% 87.23% 21ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 21ms 0.52% 87.75% 21ms 0.52% __memcpy_avx_unaligned_erms