File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3602ms, 85.78% of 4199ms total Dropped 466 nodes (cum <= 21ms) flat flat% sum% cum cum% 0 0% 0% 4190ms 99.79% __libc_start_call_main 0 0% 0% 4190ms 99.79% __libc_start_main_impl 0 0% 0% 4190ms 99.79% _start 0 0% 0% 4187ms 99.71% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4187ms 99.71% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4187ms 99.71% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4187ms 99.71% main 0 0% 0% 4147ms 98.76% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4147ms 98.76% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4147ms 98.76% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4139ms 98.57% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4125ms 98.24% AbstractDynamicLinearPdeSolver::Solve 9ms 0.21% 0.24% 2250ms 53.58% AbstractCardiacTissue::SolveCellSystems 2ms 0.048% 0.29% 1522ms 36.25% LinearSystem::Solve 0 0% 0.29% 1467ms 34.94% KSPSolve 6ms 0.14% 0.43% 1467ms 34.94% KSPSolve_Private 1ms 0.024% 0.45% 1417ms 33.75% KSPSolve_SYMMLQ 31ms 0.74% 1.19% 1311ms 31.22% AbstractCardiacCell::ComputeExceptVoltage 28ms 0.67% 1.86% 1088ms 25.91% AbstractOneStepIvpOdeSolver::InternalSolve 48ms 1.14% 3.00% 931ms 22.17% EulerIvpOdeSolver::CalculateNextYValue 4ms 0.095% 3.10% 877ms 20.89% PCApply 280ms 6.67% 9.76% 875ms 20.84% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 9.76% 863ms 20.55% PCApply_BJacobi_Singleblock 332ms 7.91% 17.67% 760ms 18.10% __GI___exp 23ms 0.55% 18.22% 734ms 17.48% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 18.24% 719ms 17.12% KSPSolve_PREONLY (partial-inline) 236ms 5.62% 23.86% 687ms 16.36% CellLuoRudy1991FromCellML::GetIIonic 4ms 0.095% 23.96% 680ms 16.19% MatSolve 0 0% 23.96% 680ms 16.19% PCApply_ICC 669ms 15.93% 39.89% 674ms 16.05% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 39.89% 651ms 15.50% KSP_PCApply.lto_priv.18 4ms 0.095% 39.99% 427ms 10.17% MatMult 388ms 9.24% 49.23% 410ms 9.76% __ieee754_exp_fma 5ms 0.12% 49.35% 408ms 9.72% MatMult_SeqAIJ (partial-inline) 402ms 9.57% 58.92% 408ms 9.72% MatMult_SeqAIJ_Inode (inline) 0 0% 58.92% 314ms 7.48% KSPConvergedDefault 75ms 1.79% 60.70% 306ms 7.29% BidomainSolver::SetupLinearSystem 0 0% 60.70% 290ms 6.91% KSP_PCApply (partial-inline) 4ms 0.095% 60.80% 222ms 5.29% std::pow (inline) 21ms 0.5% 61.30% 218ms 5.19% __pow 23ms 0.55% 61.85% 197ms 4.69% __ieee754_pow_fma 61ms 1.45% 63.30% 152ms 3.62% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 63.30% 126ms 3.00% MatNullSpaceRemove 96ms 2.29% 65.59% 96ms 2.29% log_inline (inline) 2ms 0.048% 65.63% 91ms 2.17% VecDuplicate 0 0% 65.63% 83ms 1.98% VecMDot 2ms 0.048% 65.68% 83ms 1.98% VecMXDot_Private 22ms 0.52% 66.21% 79ms 1.88% MPI_Wtime 76ms 1.81% 68.02% 76ms 1.81% exp_inline (inline) 49ms 1.17% 69.18% 61ms 1.45% AbstractOneStepIvpOdeSolver::Solve 60ms 1.43% 70.61% 60ms 1.43% ddot_ 57ms 1.36% 71.97% 59ms 1.41% VecMDot_Seq 6ms 0.14% 72.11% 58ms 1.38% PetscLogEventBeginDefault 1ms 0.024% 72.14% 57ms 1.36% __GI___clock_gettime 11ms 0.26% 72.40% 56ms 1.33% PetscLogEventEndDefault 3ms 0.071% 72.47% 56ms 1.33% VecNorm 56ms 1.33% 73.80% 56ms 1.33% [[vdso]] 15ms 0.36% 74.16% 55ms 1.31% std::__cxx11::basic_string::basic_string 34ms 0.81% 74.97% 54ms 1.29% TimeStepper::TimeStepper 6ms 0.14% 75.11% 54ms 1.29% VecCopy 26ms 0.62% 75.73% 52ms 1.24% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 39ms 0.93% 76.66% 49ms 1.17% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 76.66% 46ms 1.10% VecDot 2ms 0.048% 76.71% 46ms 1.10% VecDuplicate_MPI 0 0% 76.71% 45ms 1.07% VecNorm_Seq 0 0% 76.71% 43ms 1.02% VecDuplicate_Seq 3ms 0.071% 76.78% 43ms 1.02% VecMAXPY 43ms 1.02% 77.80% 43ms 1.02% __memcpy_avx_unaligned_erms 12ms 0.29% 78.09% 41ms 0.98% AbstractCardiacCell::SetVoltage (partial-inline) 40ms 0.95% 79.04% 40ms 0.95% [libchaste_heart.so] 2ms 0.048% 79.09% 39ms 0.93% VecAXPY 1ms 0.024% 79.11% 39ms 0.93% VecCreate 1ms 0.024% 79.14% 39ms 0.93% VecDestroy 38ms 0.9% 80.04% 38ms 0.9% __strlen_avx2 0 0% 80.04% 37ms 0.88% PetscMemcpy (inline) 0 0% 80.04% 37ms 0.88% VecDot_Seq 0 0% 80.04% 37ms 0.88% VecXDot_Seq_Private.constprop.0 12ms 0.29% 80.33% 36ms 0.86% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 80.33% 36ms 0.86% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.071% 80.40% 36ms 0.86% PetscMallocA 0 0% 80.40% 36ms 0.86% PetscTimeSubtract (inline) 0 0% 80.40% 35ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 80.40% 35ms 0.83% PMPI_Init_thread 0 0% 80.40% 35ms 0.83% PetscInitialize 0 0% 80.40% 35ms 0.83% PetscSetup::setUpWorld 0 0% 80.40% 35ms 0.83% PetscSetupUtils::CommonSetup 0 0% 80.40% 35ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 80.40% 35ms 0.83% ompi_mpi_init 0 0% 80.40% 34ms 0.81% VecNorm_MPI 0 0% 80.40% 34ms 0.81% VecNorm_MPI_Default.constprop.0 0 0% 80.40% 34ms 0.81% std::char_traits::length (inline) 1ms 0.024% 80.42% 33ms 0.79% PetscMallocAlign 32ms 0.76% 81.19% 33ms 0.79% __ieee754_log_fma 0 0% 81.19% 31ms 0.74% VecCopy_Seq (partial-inline) 30ms 0.71% 81.90% 30ms 0.71% TimeStepper::CalculateNextTime 28ms 0.67% 82.57% 30ms 0.71% VecMAXPY_Seq 0 0% 82.57% 28ms 0.67% 26ms 0.62% 83.19% 26ms 0.62% DistributedVector::Iterator::operator!= 15ms 0.36% 83.54% 26ms 0.62% TimeStepper::AdvanceOneTimeStep 1ms 0.024% 83.57% 26ms 0.62% VecAXPY_Seq 3ms 0.071% 83.64% 26ms 0.62% VecCreate_MPI_Private 0 0% 83.64% 24ms 0.57% orte_init 9ms 0.21% 83.85% 24ms 0.57% top12 (inline) 18ms 0.43% 84.28% 23ms 0.55% AbstractParameterisedSystem::GetAnyVariable 19ms 0.45% 84.73% 23ms 0.55% AbstractParameterisedSystem::SetAnyVariable 1ms 0.024% 84.76% 22ms 0.52% PetscHeaderCreate_Private 22ms 0.52% 85.28% 22ms 0.52% daxpy_ 21ms 0.5% 85.78% 21ms 0.5% std::isinf (inline)