File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3637ms, 86.49% of 4205ms total Dropped 434 nodes (cum <= 21.02ms) flat flat% sum% cum cum% 0 0% 0% 4197ms 99.81% __libc_start_call_main 0 0% 0% 4197ms 99.81% __libc_start_main_impl 0 0% 0% 4197ms 99.81% _start 0 0% 0% 4194ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4194ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4194ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4194ms 99.74% main 0 0% 0% 4157ms 98.86% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4157ms 98.86% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4157ms 98.86% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4151ms 98.72% AbstractCardiacProblem::Solve 2ms 0.048% 0.048% 4141ms 98.48% AbstractDynamicLinearPdeSolver::Solve 11ms 0.26% 0.31% 2393ms 56.91% AbstractCardiacTissue::SolveCellSystems 36ms 0.86% 1.17% 1303ms 30.99% AbstractCardiacCell::ComputeExceptVoltage 1ms 0.024% 1.19% 1241ms 29.51% LinearSystem::Solve 0 0% 1.19% 1162ms 27.63% KSPSolve 8ms 0.19% 1.38% 1162ms 27.63% KSPSolve_Private 17ms 0.4% 1.78% 1117ms 26.56% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.78% 1114ms 26.49% KSPSolve_SYMMLQ 41ms 0.98% 2.76% 942ms 22.40% EulerIvpOdeSolver::CalculateNextYValue 244ms 5.80% 8.56% 893ms 21.24% CellLuoRudy1991FromCellML::EvaluateYDerivatives 32ms 0.76% 9.32% 852ms 20.26% AbstractCardiacTissue::UpdateCaches (inline) 383ms 9.11% 18.43% 835ms 19.86% __GI___exp 294ms 6.99% 25.42% 790ms 18.79% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 25.45% 682ms 16.22% PCApply 2ms 0.048% 25.49% 678ms 16.12% PCApply_BJacobi_Singleblock 1ms 0.024% 25.52% 573ms 13.63% KSPSolve_PREONLY (partial-inline) 0 0% 25.52% 548ms 13.03% MatSolve 0 0% 25.52% 548ms 13.03% PCApply_ICC 537ms 12.77% 38.29% 542ms 12.89% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 38.29% 526ms 12.51% KSP_PCApply.lto_priv.18 6ms 0.14% 38.43% 501ms 11.91% MatMult 3ms 0.071% 38.50% 493ms 11.72% MatMult_SeqAIJ 487ms 11.58% 50.08% 492ms 11.70% MatMult_SeqAIJ_Inode (inline) 76ms 1.81% 51.89% 444ms 10.56% BidomainSolver::SetupLinearSystem 408ms 9.70% 61.59% 435ms 10.34% __ieee754_exp_fma 2ms 0.048% 61.64% 240ms 5.71% std::pow (inline) 29ms 0.69% 62.33% 238ms 5.66% __pow 1ms 0.024% 62.35% 228ms 5.42% KSPConvergedDefault 22ms 0.52% 62.88% 209ms 4.97% __ieee754_pow_fma 0 0% 62.88% 202ms 4.80% KSP_PCApply (partial-inline) 91ms 2.16% 65.04% 193ms 4.59% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 65.07% 99ms 2.35% VecDuplicate 96ms 2.28% 67.35% 96ms 2.28% log_inline (inline) 2ms 0.048% 67.40% 95ms 2.26% MatNullSpaceRemove 22ms 0.52% 67.92% 91ms 2.16% MPI_Wtime 71ms 1.69% 69.61% 73ms 1.74% exp_inline (inline) 29ms 0.69% 70.30% 71ms 1.69% TimeStepper::TimeStepper 69ms 1.64% 71.94% 69ms 1.64% [[vdso]] 0 0% 71.94% 69ms 1.64% __GI___clock_gettime 4ms 0.095% 72.03% 66ms 1.57% VecDuplicate_MPI 11ms 0.26% 72.29% 61ms 1.45% PetscLogEventBeginDefault 11ms 0.26% 72.56% 60ms 1.43% PetscLogEventEndDefault 1ms 0.024% 72.58% 56ms 1.33% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 72.58% 56ms 1.33% VecMDot 3ms 0.071% 72.65% 56ms 1.33% VecMXDot_Private 9ms 0.21% 72.87% 55ms 1.31% AbstractBidomainSolver::CheckCompatibilityCondition 5ms 0.12% 72.98% 54ms 1.28% VecCopy 51ms 1.21% 74.20% 51ms 1.21% TimeStepper::CalculateNextTime 0 0% 74.20% 51ms 1.21% VecNorm 51ms 1.21% 75.41% 51ms 1.21% std::isinf (inline) 45ms 1.07% 76.48% 47ms 1.12% __ieee754_log_fma 31ms 0.74% 77.22% 47ms 1.12% top12 (inline) 34ms 0.81% 78.03% 43ms 1.02% AbstractOneStepIvpOdeSolver::Solve 42ms 1% 79.02% 42ms 1% [libchaste_heart.so] 23ms 0.55% 79.57% 41ms 0.98% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 6ms 0.14% 79.71% 41ms 0.98% VecCreate 6ms 0.14% 79.86% 39ms 0.93% PetscMallocA 39ms 0.93% 80.78% 39ms 0.93% ddot_ 0 0% 80.78% 37ms 0.88% VecMAXPY 0 0% 80.78% 37ms 0.88% VecNorm_Seq 33ms 0.78% 81.57% 36ms 0.86% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 36ms 0.86% 82.43% 36ms 0.86% DistributedVector::Iterator::operator!= 0 0% 82.43% 34ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 82.43% 34ms 0.81% KSP_RemoveNullSpace (inline) 0 0% 82.43% 34ms 0.81% PetscSetup::setUpWorld 0 0% 82.43% 34ms 0.81% PetscSetupUtils::CommonSetup 0 0% 82.43% 34ms 0.81% PetscSetupUtils::InitialisePetsc 0 0% 82.43% 34ms 0.81% VecNorm_MPI 2ms 0.048% 82.47% 34ms 0.81% VecNorm_MPI_Default.constprop.0 34ms 0.81% 83.28% 34ms 0.81% __memcpy_avx_unaligned_erms 0 0% 83.28% 33ms 0.78% PMPI_Init_thread 0 0% 83.28% 33ms 0.78% PetscInitialize 2ms 0.048% 83.33% 33ms 0.78% PetscMallocAlign (partial-inline) 32ms 0.76% 84.09% 33ms 0.78% VecMDot_Seq 0 0% 84.09% 33ms 0.78% ompi_mpi_init 14ms 0.33% 84.42% 33ms 0.78% std::__cxx11::basic_string::basic_string 9ms 0.21% 84.64% 32ms 0.76% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 84.64% 32ms 0.76% PetscMemcpy (inline) 19ms 0.45% 85.09% 32ms 0.76% TimeStepper::AdvanceOneTimeStep 1ms 0.024% 85.11% 32ms 0.76% VecDuplicate_Seq 2ms 0.048% 85.16% 31ms 0.74% VecCreate_MPI_Private 1ms 0.024% 85.18% 31ms 0.74% VecDot 2ms 0.048% 85.23% 30ms 0.71% VecDestroy 0 0% 85.23% 27ms 0.64% VecAXPY 27ms 0.64% 85.87% 27ms 0.64% __memset_avx2_unaligned_erms 0 0% 85.87% 26ms 0.62% PetscMemzero (inline) 25ms 0.59% 86.47% 26ms 0.62% VecMAXPY_Seq 0 0% 86.47% 25ms 0.59% 1ms 0.024% 86.49% 25ms 0.59% VecCopy_Seq (partial-inline) 0 0% 86.49% 23ms 0.55% VecDot_Seq 0 0% 86.49% 23ms 0.55% VecXDot_Seq_Private.constprop.0 0 0% 86.49% 23ms 0.55% orte_init