File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3656ms, 87.09% of 4198ms total Dropped 480 nodes (cum <= 20.99ms) flat flat% sum% cum cum% 0 0% 0% 4190ms 99.81% __libc_start_call_main 0 0% 0% 4190ms 99.81% __libc_start_main_impl 0 0% 0% 4190ms 99.81% _start 0 0% 0% 4187ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4187ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4187ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4187ms 99.74% main 0 0% 0% 4146ms 98.76% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4146ms 98.76% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4146ms 98.76% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4139ms 98.59% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4128ms 98.33% AbstractDynamicLinearPdeSolver::Solve 15ms 0.36% 0.38% 2335ms 55.62% AbstractCardiacTissue::SolveCellSystems 0 0% 0.38% 1508ms 35.92% LinearSystem::Solve 0 0% 0.38% 1457ms 34.71% KSPSolve 6ms 0.14% 0.52% 1457ms 34.71% KSPSolve_Private 4ms 0.095% 0.62% 1399ms 33.33% KSPSolve_SYMMLQ 26ms 0.62% 1.24% 1344ms 32.02% AbstractCardiacCell::ComputeExceptVoltage 25ms 0.6% 1.83% 1153ms 27.47% AbstractOneStepIvpOdeSolver::InternalSolve 65ms 1.55% 3.38% 951ms 22.65% EulerIvpOdeSolver::CalculateNextYValue 3ms 0.071% 3.45% 868ms 20.68% PCApply 264ms 6.29% 9.74% 867ms 20.65% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.024% 9.77% 857ms 20.41% PCApply_BJacobi_Singleblock 29ms 0.69% 10.46% 778ms 18.53% AbstractCardiacTissue::UpdateCaches (inline) 330ms 7.86% 18.32% 756ms 18.01% __GI___exp 284ms 6.77% 25.08% 728ms 17.34% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 25.11% 723ms 17.22% KSPSolve_PREONLY (partial-inline) 3ms 0.071% 25.18% 671ms 15.98% MatSolve 0 0% 25.18% 671ms 15.98% PCApply_ICC 660ms 15.72% 40.90% 666ms 15.86% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 40.90% 651ms 15.51% KSP_PCApply.lto_priv.18 362ms 8.62% 49.52% 407ms 9.70% __ieee754_exp_fma 4ms 0.095% 49.62% 386ms 9.19% MatMult 6ms 0.14% 49.76% 381ms 9.08% MatMult_SeqAIJ (partial-inline) 368ms 8.77% 58.53% 381ms 9.08% MatMult_SeqAIJ_Inode (inline) 1ms 0.024% 58.55% 303ms 7.22% KSPConvergedDefault 2ms 0.048% 58.60% 295ms 7.03% KSP_PCApply (partial-inline) 43ms 1.02% 59.62% 239ms 5.69% BidomainSolver::SetupLinearSystem 4ms 0.095% 59.72% 225ms 5.36% std::pow (inline) 27ms 0.64% 60.36% 221ms 5.26% __pow 20ms 0.48% 60.84% 194ms 4.62% __ieee754_pow_fma 70ms 1.67% 62.51% 153ms 3.64% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.048% 62.55% 111ms 2.64% MatNullSpaceRemove 0 0% 62.55% 109ms 2.60% VecDuplicate 101ms 2.41% 64.96% 101ms 2.41% log_inline (inline) 18ms 0.43% 65.39% 78ms 1.86% MPI_Wtime 46ms 1.10% 66.48% 78ms 1.86% TimeStepper::TimeStepper 67ms 1.60% 68.08% 68ms 1.62% exp_inline (inline) 0 0% 68.08% 64ms 1.52% VecMDot 6ms 0.14% 68.22% 64ms 1.52% VecMXDot_Private 62ms 1.48% 69.70% 62ms 1.48% ddot_ 2ms 0.048% 69.75% 61ms 1.45% VecDuplicate_MPI 54ms 1.29% 71.03% 60ms 1.43% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.071% 71.11% 60ms 1.43% __GI___clock_gettime 7ms 0.17% 71.27% 59ms 1.41% PetscLogEventBeginDefault 57ms 1.36% 72.63% 57ms 1.36% [[vdso]] 3ms 0.071% 72.70% 52ms 1.24% VecDot 16ms 0.38% 73.08% 51ms 1.21% PetscLogEventEndDefault 5ms 0.12% 73.20% 51ms 1.21% PetscMallocA 51ms 1.21% 74.42% 51ms 1.21% TimeStepper::CalculateNextTime 9ms 0.21% 74.63% 50ms 1.19% VecCopy 2ms 0.048% 74.68% 50ms 1.19% VecDestroy 0 0% 74.68% 48ms 1.14% VecDuplicate_Seq 48ms 1.14% 75.82% 48ms 1.14% __ieee754_log_fma 48ms 1.14% 76.97% 48ms 1.14% std::isinf (inline) 3ms 0.071% 77.04% 46ms 1.10% PetscMallocAlign (partial-inline) 18ms 0.43% 77.47% 46ms 1.10% top12 (inline) 4ms 0.095% 77.56% 45ms 1.07% VecMAXPY 4ms 0.095% 77.66% 44ms 1.05% VecAXPY 6ms 0.14% 77.80% 44ms 1.05% VecCreate_MPI_Private 33ms 0.79% 78.59% 43ms 1.02% AbstractOneStepIvpOdeSolver::Solve 0 0% 78.59% 43ms 1.02% VecDot_Seq 0 0% 78.59% 43ms 1.02% VecXDot_Seq_Private.constprop.0 9ms 0.21% 78.80% 41ms 0.98% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 78.80% 41ms 0.98% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.024% 78.82% 39ms 0.93% VecNorm 36ms 0.86% 79.68% 38ms 0.91% VecMDot_Seq 0 0% 79.68% 36ms 0.86% CxxTest::RealWorldDescription::setUp 0 0% 79.68% 36ms 0.86% PetscInitialize 0 0% 79.68% 36ms 0.86% PetscSetup::setUpWorld 0 0% 79.68% 36ms 0.86% PetscSetupUtils::CommonSetup 0 0% 79.68% 36ms 0.86% PetscSetupUtils::InitialisePetsc 14ms 0.33% 80.01% 35ms 0.83% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 80.01% 35ms 0.83% PMPI_Init_thread 0 0% 80.01% 35ms 0.83% ompi_mpi_init 18ms 0.43% 80.44% 34ms 0.81% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 12ms 0.29% 80.73% 34ms 0.81% TimeStepper::AdvanceOneTimeStep 3ms 0.071% 80.80% 33ms 0.79% VecCreate 33ms 0.79% 81.59% 33ms 0.79% asuint64 (inline) 32ms 0.76% 82.35% 32ms 0.76% [libchaste_heart.so] 30ms 0.71% 83.06% 30ms 0.71% VecMAXPY_Seq 30ms 0.71% 83.78% 30ms 0.71% __memcpy_avx_unaligned_erms 29ms 0.69% 84.47% 29ms 0.69% DistributedVector::Iterator::operator!= 1ms 0.024% 84.49% 28ms 0.67% 0 0% 84.49% 27ms 0.64% PetscMemcpy (inline) 0 0% 84.49% 27ms 0.64% VecNorm_Seq 0 0% 84.49% 26ms 0.62% VecAXPY_Seq 3ms 0.071% 84.56% 26ms 0.62% VecSetType 5ms 0.12% 84.68% 26ms 0.62% __GI___libc_malloc 26ms 0.62% 85.30% 26ms 0.62% daxpy_ 25ms 0.6% 85.90% 25ms 0.6% DistributedVector::Stripe::operator[] (inline) 0 0% 85.90% 25ms 0.6% PetscMemzero (inline) 0 0% 85.90% 25ms 0.6% orte_init 24ms 0.57% 86.47% 24ms 0.57% [libchaste_ode.so] 24ms 0.57% 87.04% 24ms 0.57% __memset_avx2_unaligned_erms 1ms 0.024% 87.07% 24ms 0.57% __posix_memalign 1ms 0.024% 87.09% 21ms 0.5% PetscHeaderDestroy_Private 0 0% 87.09% 21ms 0.5% VecNorm_MPI 0 0% 87.09% 21ms 0.5% VecNorm_MPI_Default.constprop.0