File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3634ms, 87.31% of 4162ms total Dropped 459 nodes (cum <= 20.81ms) flat flat% sum% cum cum% 0 0% 0% 4154ms 99.81% __libc_start_call_main 0 0% 0% 4154ms 99.81% __libc_start_main_impl 0 0% 0% 4154ms 99.81% _start 0 0% 0% 4151ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4151ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4151ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4151ms 99.74% main 0 0% 0% 4112ms 98.80% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4112ms 98.80% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4112ms 98.80% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4104ms 98.61% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4091ms 98.29% AbstractDynamicLinearPdeSolver::Solve 9ms 0.22% 0.24% 2348ms 56.42% AbstractCardiacTissue::SolveCellSystems 0 0% 0.24% 1350ms 32.44% LinearSystem::Solve 34ms 0.82% 1.06% 1342ms 32.24% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.06% 1310ms 31.48% KSPSolve 5ms 0.12% 1.18% 1310ms 31.48% KSPSolve_Private 1ms 0.024% 1.20% 1284ms 30.85% KSPSolve_SYMMLQ 31ms 0.74% 1.95% 1145ms 27.51% AbstractOneStepIvpOdeSolver::InternalSolve 57ms 1.37% 3.32% 984ms 23.64% EulerIvpOdeSolver::CalculateNextYValue 273ms 6.56% 9.88% 910ms 21.86% CellLuoRudy1991FromCellML::EvaluateYDerivatives 371ms 8.91% 18.79% 821ms 19.73% __GI___exp 25ms 0.6% 19.39% 786ms 18.89% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 19.41% 741ms 17.80% PCApply 250ms 6.01% 25.42% 740ms 17.78% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 25.44% 734ms 17.64% PCApply_BJacobi_Singleblock 0 0% 25.44% 618ms 14.85% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 25.47% 584ms 14.03% PCApply_ICC 4ms 0.096% 25.56% 583ms 14.01% MatSolve 570ms 13.70% 39.26% 574ms 13.79% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 39.26% 563ms 13.53% KSP_PCApply.lto_priv.18 2ms 0.048% 39.31% 510ms 12.25% MatMult 2ms 0.048% 39.36% 501ms 12.04% MatMult_SeqAIJ 496ms 11.92% 51.27% 501ms 12.04% MatMult_SeqAIJ_Inode (inline) 404ms 9.71% 60.98% 434ms 10.43% __ieee754_exp_fma 66ms 1.59% 62.57% 353ms 8.48% BidomainSolver::SetupLinearSystem 2ms 0.048% 62.61% 262ms 6.30% KSPConvergedDefault 1ms 0.024% 62.64% 257ms 6.17% std::pow (inline) 36ms 0.86% 63.50% 256ms 6.15% __pow 0 0% 63.50% 240ms 5.77% KSP_PCApply (partial-inline) 25ms 0.6% 64.10% 220ms 5.29% __ieee754_pow_fma 82ms 1.97% 66.07% 171ms 4.11% CellLuoRudy1991FromCellML::VerifyStateVariables 107ms 2.57% 68.64% 107ms 2.57% log_inline (inline) 1ms 0.024% 68.67% 91ms 2.19% MatNullSpaceRemove 84ms 2.02% 70.69% 84ms 2.02% exp_inline (inline) 1ms 0.024% 70.71% 75ms 1.80% VecDuplicate 17ms 0.41% 71.12% 73ms 1.75% MPI_Wtime 10ms 0.24% 71.36% 57ms 1.37% PetscLogEventBeginDefault 1ms 0.024% 71.38% 57ms 1.37% VecMDot 4ms 0.096% 71.48% 56ms 1.35% VecMXDot_Private 1ms 0.024% 71.50% 56ms 1.35% __GI___clock_gettime 56ms 1.35% 72.85% 56ms 1.35% ddot_ 55ms 1.32% 74.17% 55ms 1.32% [[vdso]] 38ms 0.91% 75.08% 54ms 1.30% TimeStepper::TimeStepper 13ms 0.31% 75.40% 52ms 1.25% PetscLogEventEndDefault 3ms 0.072% 75.47% 51ms 1.23% VecCopy 41ms 0.99% 76.45% 49ms 1.18% AbstractOneStepIvpOdeSolver::Solve 43ms 1.03% 77.49% 48ms 1.15% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.048% 77.53% 46ms 1.11% VecDestroy 3ms 0.072% 77.61% 43ms 1.03% VecDot 0 0% 77.61% 42ms 1.01% VecNorm 8ms 0.19% 77.80% 40ms 0.96% AbstractCardiacCell::SetVoltage (partial-inline) 4ms 0.096% 77.90% 40ms 0.96% PetscTimeSubtract (inline) 3ms 0.072% 77.97% 39ms 0.94% VecAXPY 39ms 0.94% 78.90% 39ms 0.94% std::isinf (inline) 2ms 0.048% 78.95% 38ms 0.91% VecDuplicate_Seq 38ms 0.91% 79.87% 38ms 0.91% __memcpy_avx_unaligned_erms 12ms 0.29% 80.15% 36ms 0.86% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 80.15% 36ms 0.86% VecDot_Seq 4ms 0.096% 80.25% 36ms 0.86% VecDuplicate_MPI 0 0% 80.25% 36ms 0.86% VecXDot_Seq_Private.constprop.0 0 0% 80.25% 35ms 0.84% PetscMemcpy (inline) 0 0% 80.25% 35ms 0.84% VecNorm_Seq 0 0% 80.25% 34ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 80.25% 34ms 0.82% PMPI_Init_thread 0 0% 80.25% 34ms 0.82% PetscInitialize 0 0% 80.25% 34ms 0.82% PetscSetup::setUpWorld 0 0% 80.25% 34ms 0.82% PetscSetupUtils::CommonSetup 0 0% 80.25% 34ms 0.82% PetscSetupUtils::InitialisePetsc 4ms 0.096% 80.35% 34ms 0.82% VecCreate 34ms 0.82% 81.16% 34ms 0.82% [libchaste_heart.so] 0 0% 81.16% 34ms 0.82% ompi_mpi_init 7ms 0.17% 81.33% 33ms 0.79% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.33% 33ms 0.79% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.072% 81.40% 33ms 0.79% VecMAXPY 12ms 0.29% 81.69% 33ms 0.79% top12 (inline) 8ms 0.19% 81.88% 32ms 0.77% PetscMallocA 29ms 0.7% 82.58% 31ms 0.74% VecMDot_Seq 10ms 0.24% 82.82% 31ms 0.74% std::__cxx11::basic_string::basic_string 1ms 0.024% 82.84% 29ms 0.7% VecCopy_Seq (partial-inline) 0 0% 82.84% 29ms 0.7% VecNorm_MPI 0 0% 82.84% 29ms 0.7% VecNorm_MPI_Default.constprop.0 29ms 0.7% 83.54% 29ms 0.7% __ieee754_log_fma 0 0% 83.54% 28ms 0.67% 27ms 0.65% 84.19% 28ms 0.67% AbstractParameterisedSystem::SetAnyVariable 18ms 0.43% 84.62% 28ms 0.67% TimeStepper::AdvanceOneTimeStep 19ms 0.46% 85.08% 25ms 0.6% AbstractParameterisedSystem::GetAnyVariable 3ms 0.072% 85.15% 24ms 0.58% PetscMallocAlign 0 0% 85.15% 24ms 0.58% orte_init 1ms 0.024% 85.18% 23ms 0.55% VecAXPY_Seq 22ms 0.53% 85.70% 22ms 0.53% asuint64 (inline) 4ms 0.096% 85.80% 21ms 0.5% PetscHeaderDestroy_Private 21ms 0.5% 86.30% 21ms 0.5% TimeStepper::CalculateNextTime 21ms 0.5% 86.81% 21ms 0.5% daxpy_ 21ms 0.5% 87.31% 21ms 0.5% std::vector::operator[] (inline)