File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3859ms, 89.29% of 4322ms total Dropped 421 nodes (cum <= 21.61ms) flat flat% sum% cum cum% 0 0% 0% 4315ms 99.84% __libc_start_call_main 0 0% 0% 4315ms 99.84% __libc_start_main_impl 0 0% 0% 4315ms 99.84% _start 0 0% 0% 4312ms 99.77% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4312ms 99.77% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4312ms 99.77% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4312ms 99.77% main 0 0% 0% 4274ms 98.89% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4273ms 98.87% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4273ms 98.87% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4268ms 98.75% AbstractCardiacProblem::Solve 0 0% 0% 4257ms 98.50% AbstractDynamicLinearPdeSolver::Solve 10ms 0.23% 0.23% 2056ms 47.57% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.25% 1731ms 40.05% LinearSystem::Solve 0 0% 0.25% 1687ms 39.03% KSPSolve 1ms 0.023% 0.28% 1687ms 39.03% KSPSolve_Private 1ms 0.023% 0.3% 1662ms 38.45% KSPSolve_SYMMLQ 22ms 0.51% 0.81% 1157ms 26.77% AbstractCardiacCell::ComputeExceptVoltage 14ms 0.32% 1.13% 1048ms 24.25% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.046% 1.18% 963ms 22.28% PCApply 1ms 0.023% 1.20% 957ms 22.14% PCApply_BJacobi_Singleblock 95ms 2.20% 3.40% 892ms 20.64% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.40% 815ms 18.86% KSPSolve_PREONLY (partial-inline) 292ms 6.76% 10.16% 790ms 18.28% CellLuoRudy1991FromCellML::EvaluateYDerivatives 7ms 0.16% 10.32% 789ms 18.26% MatMult 2ms 0.046% 10.37% 774ms 17.91% MatMult_SeqAIJ 766ms 17.72% 28.09% 774ms 17.91% MatMult_SeqAIJ_Inode (inline) 2ms 0.046% 28.14% 764ms 17.68% MatSolve 0 0% 28.14% 764ms 17.68% PCApply_ICC 746ms 17.26% 45.40% 752ms 17.40% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 18ms 0.42% 45.81% 745ms 17.24% AbstractCardiacTissue::UpdateCaches (inline) 325ms 7.52% 53.33% 705ms 16.31% CellLuoRudy1991FromCellML::GetIIonic 0 0% 53.33% 703ms 16.27% KSP_PCApply.lto_priv.18 217ms 5.02% 58.35% 581ms 13.44% __GI___exp 41ms 0.95% 59.30% 429ms 9.93% BidomainSolver::SetupLinearSystem 0 0% 59.30% 326ms 7.54% KSPConvergedDefault 0 0% 59.30% 323ms 7.47% KSP_PCApply (partial-inline) 319ms 7.38% 66.68% 322ms 7.45% __ieee754_exp_fma 3ms 0.069% 66.75% 205ms 4.74% std::pow (inline) 23ms 0.53% 67.28% 199ms 4.60% __pow 6ms 0.14% 67.42% 174ms 4.03% __ieee754_pow_fma 126ms 2.92% 70.34% 126ms 2.92% [libchaste_heart.so] 3ms 0.069% 70.41% 122ms 2.82% MatNullSpaceRemove 76ms 1.76% 72.17% 112ms 2.59% CellLuoRudy1991FromCellML::VerifyStateVariables 101ms 2.34% 74.50% 101ms 2.34% log_inline (inline) 25ms 0.58% 75.08% 97ms 2.24% MPI_Wtime 0 0% 75.08% 84ms 1.94% VecDuplicate 0 0% 75.08% 78ms 1.80% VecMDot 3ms 0.069% 75.15% 78ms 1.80% VecMXDot_Private 29ms 0.67% 75.82% 73ms 1.69% TimeStepper::TimeStepper 4ms 0.093% 75.91% 72ms 1.67% __GI___clock_gettime 69ms 1.60% 77.51% 69ms 1.60% [[vdso]] 58ms 1.34% 78.85% 66ms 1.53% exp_inline (inline) 63ms 1.46% 80.31% 63ms 1.46% TimeStepper::CalculateNextTime 10ms 0.23% 80.54% 59ms 1.37% PetscLogEventBeginDefault 7ms 0.16% 80.70% 57ms 1.32% PetscLogEventEndDefault 50ms 1.16% 81.86% 50ms 1.16% [libm.so.6] 44ms 1.02% 82.88% 46ms 1.06% VecMDot_Seq 46ms 1.06% 83.94% 46ms 1.06% ddot_ 3ms 0.069% 84.01% 44ms 1.02% VecDuplicate_MPI 8ms 0.19% 84.20% 43ms 0.99% VecCopy 3ms 0.069% 84.27% 43ms 0.99% VecDestroy 4ms 0.093% 84.36% 41ms 0.95% VecMAXPY 5ms 0.12% 84.47% 40ms 0.93% PetscMallocA 0 0% 84.47% 40ms 0.93% VecDuplicate_Seq 1ms 0.023% 84.50% 39ms 0.9% VecCreate 5ms 0.12% 84.61% 39ms 0.9% VecDot 8ms 0.19% 84.80% 36ms 0.83% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.80% 36ms 0.83% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.069% 84.87% 36ms 0.83% VecAXPY 8ms 0.19% 85.05% 35ms 0.81% AbstractCardiacCell::SetVoltage (partial-inline) 2ms 0.046% 85.10% 35ms 0.81% PetscMallocAlign 0 0% 85.10% 34ms 0.79% CxxTest::RealWorldDescription::setUp 0 0% 85.10% 34ms 0.79% PMPI_Init_thread 0 0% 85.10% 34ms 0.79% PetscInitialize 0 0% 85.10% 34ms 0.79% PetscSetup::setUpWorld 0 0% 85.10% 34ms 0.79% PetscSetupUtils::CommonSetup 0 0% 85.10% 34ms 0.79% PetscSetupUtils::InitialisePetsc 0 0% 85.10% 34ms 0.79% ompi_mpi_init 33ms 0.76% 85.86% 33ms 0.76% [libchaste_ode.so] 3ms 0.069% 85.93% 32ms 0.74% PMPI_Comm_get_attr 3ms 0.069% 86.00% 32ms 0.74% VecNorm 5ms 0.12% 86.12% 31ms 0.72% TimeStepper::AdvanceOneTimeStep 0 0% 86.12% 31ms 0.72% VecDot_Seq 1ms 0.023% 86.14% 31ms 0.72% VecXDot_Seq_Private.constprop.0 6ms 0.14% 86.28% 29ms 0.67% ompi_attr_get_c 1ms 0.023% 86.30% 28ms 0.65% 2ms 0.046% 86.35% 27ms 0.62% VecCreate_MPI_Private 26ms 0.6% 86.95% 26ms 0.6% VecMAXPY_Seq 1ms 0.023% 86.97% 26ms 0.6% VecNorm_Seq 26ms 0.6% 87.58% 26ms 0.6% __ieee754_log_fma 26ms 0.6% 88.18% 26ms 0.6% __memcpy_avx512_unaligned_erms 18ms 0.42% 88.59% 24ms 0.56% AbstractOneStepIvpOdeSolver::Solve 0 0% 88.59% 24ms 0.56% PetscMemcpy (inline) 4ms 0.093% 88.69% 24ms 0.56% __posix_memalign 23ms 0.53% 89.22% 23ms 0.53% ReplicatableVector::operator[] 3ms 0.069% 89.29% 23ms 0.53% VecAXPY_Seq