File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3659ms, 87.35% of 4189ms total Dropped 451 nodes (cum <= 20.95ms) flat flat% sum% cum cum% 0 0% 0% 4181ms 99.81% __libc_start_call_main 0 0% 0% 4181ms 99.81% __libc_start_main_impl 0 0% 0% 4181ms 99.81% _start 0 0% 0% 4179ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4179ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4179ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4179ms 99.76% main 0 0% 0% 4140ms 98.83% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4140ms 98.83% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4140ms 98.83% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4132ms 98.64% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4114ms 98.21% AbstractDynamicLinearPdeSolver::Solve 17ms 0.41% 0.43% 2338ms 55.81% AbstractCardiacTissue::SolveCellSystems 0 0% 0.43% 1503ms 35.88% LinearSystem::Solve 0 0% 0.43% 1457ms 34.78% KSPSolve 2ms 0.048% 0.48% 1457ms 34.78% KSPSolve_Private 1ms 0.024% 0.5% 1400ms 33.42% KSPSolve_SYMMLQ 40ms 0.95% 1.46% 1353ms 32.30% AbstractCardiacCell::ComputeExceptVoltage 36ms 0.86% 2.32% 1171ms 27.95% AbstractOneStepIvpOdeSolver::InternalSolve 59ms 1.41% 3.72% 962ms 22.96% EulerIvpOdeSolver::CalculateNextYValue 239ms 5.71% 9.43% 881ms 21.03% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.024% 9.45% 864ms 20.63% PCApply 0 0% 9.45% 859ms 20.51% PCApply_BJacobi_Singleblock 349ms 8.33% 17.78% 786ms 18.76% __GI___exp 31ms 0.74% 18.52% 771ms 18.41% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 18.52% 727ms 17.35% KSPSolve_PREONLY (partial-inline) 260ms 6.21% 24.73% 717ms 17.12% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.048% 24.78% 681ms 16.26% MatSolve 0 0% 24.78% 681ms 16.26% PCApply_ICC 672ms 16.04% 40.82% 675ms 16.11% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.82% 627ms 14.97% KSP_PCApply.lto_priv.18 386ms 9.21% 50.04% 419ms 10.00% __ieee754_exp_fma 4ms 0.095% 50.13% 408ms 9.74% MatMult 5ms 0.12% 50.25% 399ms 9.52% MatMult_SeqAIJ (partial-inline) 391ms 9.33% 59.58% 399ms 9.52% MatMult_SeqAIJ_Inode (inline) 4ms 0.095% 59.68% 316ms 7.54% KSPConvergedDefault 0 0% 59.68% 306ms 7.30% KSP_PCApply (partial-inline) 47ms 1.12% 60.80% 245ms 5.85% BidomainSolver::SetupLinearSystem 21ms 0.5% 61.30% 241ms 5.75% __pow 0 0% 61.30% 241ms 5.75% std::pow (inline) 30ms 0.72% 62.02% 220ms 5.25% __ieee754_pow_fma 70ms 1.67% 63.69% 155ms 3.70% CellLuoRudy1991FromCellML::VerifyStateVariables 113ms 2.70% 66.39% 113ms 2.70% log_inline (inline) 3ms 0.072% 66.46% 111ms 2.65% MatNullSpaceRemove 0 0% 66.46% 105ms 2.51% VecDuplicate 47ms 1.12% 67.58% 81ms 1.93% TimeStepper::TimeStepper 69ms 1.65% 69.23% 71ms 1.69% exp_inline (inline) 16ms 0.38% 69.61% 68ms 1.62% MPI_Wtime 5ms 0.12% 69.73% 68ms 1.62% VecCopy 68ms 1.62% 71.35% 68ms 1.62% ddot_ 19ms 0.45% 71.81% 67ms 1.60% PetscLogEventEndDefault 0 0% 71.81% 65ms 1.55% VecMDot 7ms 0.17% 71.97% 65ms 1.55% VecMXDot_Private 2ms 0.048% 72.02% 60ms 1.43% VecDuplicate_MPI 0 0% 72.02% 59ms 1.41% VecDot 5ms 0.12% 72.14% 53ms 1.27% PetscLogEventBeginDefault 1ms 0.024% 72.17% 52ms 1.24% __GI___clock_gettime 52ms 1.24% 73.41% 52ms 1.24% __ieee754_log_fma 51ms 1.22% 74.62% 51ms 1.22% [[vdso]] 51ms 1.22% 75.84% 51ms 1.22% __memcpy_avx_unaligned_erms 0 0% 75.84% 49ms 1.17% VecDot_Seq 0 0% 75.84% 49ms 1.17% VecXDot_Seq_Private.constprop.0 5ms 0.12% 75.96% 48ms 1.15% PetscMallocA 0 0% 75.96% 48ms 1.15% VecDestroy 2ms 0.048% 76.01% 47ms 1.12% VecCreate 1ms 0.024% 76.03% 45ms 1.07% VecDuplicate_Seq 43ms 1.03% 77.06% 45ms 1.07% VecMDot_Seq 2ms 0.048% 77.11% 43ms 1.03% PetscMallocAlign (partial-inline) 2ms 0.048% 77.15% 43ms 1.03% VecMAXPY 1ms 0.024% 77.18% 42ms 1.00% VecAXPY 19ms 0.45% 77.63% 41ms 0.98% top12 (inline) 7ms 0.17% 77.80% 39ms 0.93% AbstractCardiacCell::SetVoltage (partial-inline) 20ms 0.48% 78.28% 39ms 0.93% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 2ms 0.048% 78.32% 37ms 0.88% VecNorm 27ms 0.64% 78.97% 36ms 0.86% AbstractOneStepIvpOdeSolver::Solve 0 0% 78.97% 36ms 0.86% PetscMemcpy (inline) 36ms 0.86% 79.83% 36ms 0.86% TimeStepper::CalculateNextTime 36ms 0.86% 80.69% 36ms 0.86% std::isinf (inline) 29ms 0.69% 81.38% 34ms 0.81% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 81.38% 34ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 81.38% 34ms 0.81% PetscInitialize 0 0% 81.38% 34ms 0.81% PetscSetup::setUpWorld 0 0% 81.38% 34ms 0.81% PetscSetupUtils::CommonSetup 0 0% 81.38% 34ms 0.81% PetscSetupUtils::InitialisePetsc 0 0% 81.38% 33ms 0.79% PMPI_Init_thread 0 0% 81.38% 33ms 0.79% VecCopy_Seq (partial-inline) 0 0% 81.38% 33ms 0.79% ompi_mpi_init 32ms 0.76% 82.14% 32ms 0.76% [libchaste_heart.so] 1ms 0.024% 82.17% 31ms 0.74% VecCreate_MPI_Private 19ms 0.45% 82.62% 30ms 0.72% TimeStepper::AdvanceOneTimeStep 1ms 0.024% 82.65% 29ms 0.69% VecAXPY_Seq 0 0% 82.65% 29ms 0.69% VecNorm_Seq 12ms 0.29% 82.93% 29ms 0.69% __GI___libc_malloc 14ms 0.33% 83.27% 28ms 0.67% std::__cxx11::basic_string::basic_string 0 0% 83.27% 27ms 0.64% 3ms 0.072% 83.34% 27ms 0.64% __posix_memalign 27ms 0.64% 83.98% 27ms 0.64% std::vector::operator[] (inline) 0 0% 83.98% 26ms 0.62% orte_init 25ms 0.6% 84.58% 25ms 0.6% AbstractParameterisedSystem::SetAnyVariable 0 0% 84.58% 24ms 0.57% VecSetType 24ms 0.57% 85.15% 24ms 0.57% daxpy_ 2ms 0.048% 85.20% 23ms 0.55% PetscHeaderCreate_Private 0 0% 85.20% 23ms 0.55% PetscMemzero (inline) 23ms 0.55% 85.75% 23ms 0.55% __memset_avx2_unaligned_erms 4ms 0.095% 85.84% 23ms 0.55% std::vector::_M_assign_aux (inline) 0 0% 85.84% 23ms 0.55% std::vector::assign (inline) 15ms 0.36% 86.20% 22ms 0.53% AbstractParameterisedSystem::GetAnyVariable 5ms 0.12% 86.32% 22ms 0.53% PetscHeaderDestroy_Private 1ms 0.024% 86.35% 22ms 0.53% VecCreate_Seq 22ms 0.53% 86.87% 22ms 0.53% asuint64 (inline) 20ms 0.48% 87.35% 21ms 0.5% VecMAXPY_Seq 0 0% 87.35% 21ms 0.5% VecNorm_MPI 0 0% 87.35% 21ms 0.5% VecNorm_MPI_Default.constprop.0