File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3647ms, 87.23% of 4181ms total Dropped 477 nodes (cum <= 20.91ms) flat flat% sum% cum cum% 0 0% 0% 4173ms 99.81% __libc_start_call_main 0 0% 0% 4173ms 99.81% __libc_start_main_impl 0 0% 0% 4173ms 99.81% _start 0 0% 0% 4170ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4170ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4170ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4170ms 99.74% main 0 0% 0% 4135ms 98.90% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4135ms 98.90% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4135ms 98.90% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4127ms 98.71% AbstractCardiacProblem::Solve 2ms 0.048% 0.048% 4114ms 98.40% AbstractDynamicLinearPdeSolver::Solve 12ms 0.29% 0.33% 2311ms 55.27% AbstractCardiacTissue::SolveCellSystems 2ms 0.048% 0.38% 1510ms 36.12% LinearSystem::Solve 0 0% 0.38% 1452ms 34.73% KSPSolve 3ms 0.072% 0.45% 1452ms 34.73% KSPSolve_Private 0 0% 0.45% 1407ms 33.65% KSPSolve_SYMMLQ 38ms 0.91% 1.36% 1352ms 32.34% AbstractCardiacCell::ComputeExceptVoltage 27ms 0.65% 2.01% 1135ms 27.15% AbstractOneStepIvpOdeSolver::InternalSolve 61ms 1.46% 3.47% 962ms 23.01% EulerIvpOdeSolver::CalculateNextYValue 258ms 6.17% 9.64% 885ms 21.17% CellLuoRudy1991FromCellML::EvaluateYDerivatives 3ms 0.072% 9.71% 873ms 20.88% PCApply 2ms 0.048% 9.76% 866ms 20.71% PCApply_BJacobi_Singleblock 349ms 8.35% 18.11% 787ms 18.82% __GI___exp 19ms 0.45% 18.56% 743ms 17.77% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 18.56% 719ms 17.20% KSPSolve_PREONLY (partial-inline) 246ms 5.88% 24.44% 694ms 16.60% CellLuoRudy1991FromCellML::GetIIonic 4ms 0.096% 24.54% 672ms 16.07% PCApply_ICC 3ms 0.072% 24.61% 668ms 15.98% MatSolve 655ms 15.67% 40.28% 660ms 15.79% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 40.28% 632ms 15.12% KSP_PCApply.lto_priv.18 384ms 9.18% 49.46% 417ms 9.97% __ieee754_exp_fma 6ms 0.14% 49.61% 410ms 9.81% MatMult 7ms 0.17% 49.77% 396ms 9.47% MatMult_SeqAIJ (partial-inline) 389ms 9.30% 59.08% 396ms 9.47% MatMult_SeqAIJ_Inode (inline) 2ms 0.048% 59.12% 328ms 7.85% KSPConvergedDefault 0 0% 59.12% 317ms 7.58% KSP_PCApply (partial-inline) 39ms 0.93% 60.06% 243ms 5.81% BidomainSolver::SetupLinearSystem 3ms 0.072% 60.13% 224ms 5.36% std::pow (inline) 19ms 0.45% 60.58% 221ms 5.29% __pow 25ms 0.6% 61.18% 202ms 4.83% __ieee754_pow_fma 74ms 1.77% 62.95% 177ms 4.23% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 62.95% 119ms 2.85% MatNullSpaceRemove 0 0% 62.95% 117ms 2.80% VecDuplicate 104ms 2.49% 65.44% 104ms 2.49% log_inline (inline) 18ms 0.43% 65.87% 73ms 1.75% MPI_Wtime 0 0% 65.87% 71ms 1.70% VecMDot 2ms 0.048% 65.92% 71ms 1.70% VecMXDot_Private 3ms 0.072% 65.99% 68ms 1.63% VecDuplicate_MPI 62ms 1.48% 67.47% 65ms 1.55% exp_inline (inline) 15ms 0.36% 67.83% 61ms 1.46% PetscLogEventEndDefault 29ms 0.69% 68.52% 60ms 1.44% TimeStepper::TimeStepper 59ms 1.41% 69.94% 59ms 1.41% ddot_ 3ms 0.072% 70.01% 56ms 1.34% VecDot 16ms 0.38% 70.39% 56ms 1.34% std::__cxx11::basic_string::basic_string 1ms 0.024% 70.41% 55ms 1.32% __GI___clock_gettime 3ms 0.072% 70.49% 54ms 1.29% VecCopy 54ms 1.29% 71.78% 54ms 1.29% [[vdso]] 7ms 0.17% 71.94% 52ms 1.24% PetscLogEventBeginDefault 50ms 1.20% 73.14% 52ms 1.24% VecMDot_Seq 5ms 0.12% 73.26% 51ms 1.22% PetscMallocA 0 0% 73.26% 49ms 1.17% VecDuplicate_Seq 30ms 0.72% 73.98% 48ms 1.15% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 37ms 0.88% 74.86% 48ms 1.15% AbstractOneStepIvpOdeSolver::Solve 4ms 0.096% 74.96% 48ms 1.15% VecCreate 3ms 0.072% 75.03% 48ms 1.15% VecMAXPY 48ms 1.15% 76.18% 48ms 1.15% __memcpy_avx_unaligned_erms 2ms 0.048% 76.23% 46ms 1.10% PetscMallocAlign 0 0% 76.23% 46ms 1.10% VecDot_Seq 0 0% 76.23% 46ms 1.10% VecXDot_Seq_Private.constprop.0 3ms 0.072% 76.30% 45ms 1.08% VecDestroy 1ms 0.024% 76.32% 43ms 1.03% AbstractBidomainSolver::FinaliseLinearSystem 43ms 1.03% 77.35% 43ms 1.03% [libchaste_heart.so] 14ms 0.33% 77.68% 42ms 1.00% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 77.68% 42ms 1.00% PetscMemcpy (inline) 42ms 1.00% 78.69% 42ms 1.00% TimeStepper::CalculateNextTime 7ms 0.17% 78.86% 41ms 0.98% AbstractCardiacCell::SetVoltage (partial-inline) 12ms 0.29% 79.14% 40ms 0.96% top12 (inline) 1ms 0.024% 79.17% 39ms 0.93% VecAXPY 1ms 0.024% 79.19% 39ms 0.93% VecNorm 5ms 0.12% 79.31% 38ms 0.91% VecCreate_MPI_Private 33ms 0.79% 80.10% 37ms 0.88% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 36ms 0.86% 80.96% 36ms 0.86% __ieee754_log_fma 4ms 0.096% 81.06% 33ms 0.79% __posix_memalign 1ms 0.024% 81.08% 32ms 0.77% VecCopy_Seq (partial-inline) 32ms 0.77% 81.85% 32ms 0.77% asuint64 (inline) 0 0% 81.85% 31ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 81.85% 31ms 0.74% PetscInitialize 0 0% 81.85% 31ms 0.74% PetscSetup::setUpWorld 0 0% 81.85% 31ms 0.74% PetscSetupUtils::CommonSetup 0 0% 81.85% 31ms 0.74% PetscSetupUtils::InitialisePetsc 0 0% 81.85% 31ms 0.74% VecNorm_Seq 0 0% 81.85% 31ms 0.74% std::char_traits::length (inline) 0 0% 81.85% 30ms 0.72% PMPI_Init_thread 0 0% 81.85% 30ms 0.72% ompi_mpi_init 1ms 0.024% 81.87% 28ms 0.67% VecAXPY_Seq 22ms 0.53% 82.40% 27ms 0.65% AbstractParameterisedSystem::GetAnyVariable 27ms 0.65% 83.04% 27ms 0.65% AbstractParameterisedSystem::SetAnyVariable 0 0% 83.04% 27ms 0.65% VecNorm_MPI 0 0% 83.04% 27ms 0.65% VecNorm_MPI_Default.constprop.0 2ms 0.048% 83.09% 27ms 0.65% VecSetType 27ms 0.65% 83.74% 27ms 0.65% __strlen_avx2 27ms 0.65% 84.38% 27ms 0.65% std::isinf (inline) 8ms 0.19% 84.57% 26ms 0.62% TimeStepper::AdvanceOneTimeStep 26ms 0.62% 85.19% 26ms 0.62% VecMAXPY_Seq 10ms 0.24% 85.43% 26ms 0.62% __GI___libc_malloc 25ms 0.6% 86.03% 25ms 0.6% daxpy_ 0 0% 86.03% 24ms 0.57% 0 0% 86.03% 23ms 0.55% orte_init 8ms 0.19% 86.22% 22ms 0.53% AbstractCardiacCell::GetVoltage (partial-inline) 21ms 0.5% 86.73% 21ms 0.5% TimeStepper::GetNextTimeStep 21ms 0.5% 87.23% 21ms 0.5% [libm.so.6]