File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3656ms, 87.38% of 4184ms total Dropped 445 nodes (cum <= 20.92ms) flat flat% sum% cum cum% 0 0% 0% 4176ms 99.81% __libc_start_call_main 0 0% 0% 4176ms 99.81% __libc_start_main_impl 0 0% 0% 4176ms 99.81% _start 0 0% 0% 4173ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4173ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4173ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4173ms 99.74% main 0 0% 0% 4138ms 98.90% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4138ms 98.90% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4138ms 98.90% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4130ms 98.71% AbstractCardiacProblem::Solve 2ms 0.048% 0.048% 4123ms 98.54% AbstractDynamicLinearPdeSolver::Solve 10ms 0.24% 0.29% 2289ms 54.71% AbstractCardiacTissue::SolveCellSystems 1ms 0.024% 0.31% 1548ms 37.00% LinearSystem::Solve 0 0% 0.31% 1496ms 35.76% KSPSolve 5ms 0.12% 0.43% 1496ms 35.76% KSPSolve_Private 1ms 0.024% 0.45% 1454ms 34.75% KSPSolve_SYMMLQ 44ms 1.05% 1.51% 1351ms 32.29% AbstractCardiacCell::ComputeExceptVoltage 25ms 0.6% 2.10% 1142ms 27.29% AbstractOneStepIvpOdeSolver::InternalSolve 69ms 1.65% 3.75% 965ms 23.06% EulerIvpOdeSolver::CalculateNextYValue 3ms 0.072% 3.82% 921ms 22.01% PCApply 1ms 0.024% 3.85% 912ms 21.80% PCApply_BJacobi_Singleblock 228ms 5.45% 9.30% 876ms 20.94% CellLuoRudy1991FromCellML::EvaluateYDerivatives 384ms 9.18% 18.48% 797ms 19.05% __GI___exp 1ms 0.024% 18.50% 780ms 18.64% KSPSolve_PREONLY (partial-inline) 2ms 0.048% 18.55% 741ms 17.71% PCApply_ICC 6ms 0.14% 18.69% 739ms 17.66% MatSolve 722ms 17.26% 35.95% 729ms 17.42% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 15ms 0.36% 36.30% 715ms 17.09% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 36.33% 683ms 16.32% KSP_PCApply.lto_priv.18 240ms 5.74% 42.07% 672ms 16.06% CellLuoRudy1991FromCellML::GetIIonic 365ms 8.72% 50.79% 389ms 9.30% __ieee754_exp_fma 3ms 0.072% 50.86% 380ms 9.08% MatMult 3ms 0.072% 50.93% 370ms 8.84% MatMult_SeqAIJ 364ms 8.70% 59.63% 368ms 8.80% MatMult_SeqAIJ_Inode (inline) 3ms 0.072% 59.70% 318ms 7.60% KSPConvergedDefault 0 0% 59.70% 302ms 7.22% KSP_PCApply (partial-inline) 38ms 0.91% 60.61% 236ms 5.64% BidomainSolver::SetupLinearSystem 5ms 0.12% 60.73% 227ms 5.43% std::pow (inline) 24ms 0.57% 61.30% 222ms 5.31% __pow 32ms 0.76% 62.07% 198ms 4.73% __ieee754_pow_fma 67ms 1.60% 63.67% 164ms 3.92% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 63.70% 105ms 2.51% MatNullSpaceRemove 0 0% 63.70% 88ms 2.10% VecDuplicate 87ms 2.08% 65.77% 87ms 2.08% log_inline (inline) 26ms 0.62% 66.40% 82ms 1.96% MPI_Wtime 81ms 1.94% 68.33% 81ms 1.94% ddot_ 74ms 1.77% 70.10% 74ms 1.77% exp_inline (inline) 38ms 0.91% 71.01% 72ms 1.72% TimeStepper::TimeStepper 0 0% 71.01% 64ms 1.53% VecMDot 2ms 0.048% 71.06% 64ms 1.53% VecMXDot_Private 1ms 0.024% 71.08% 63ms 1.51% VecDot 7ms 0.17% 71.25% 58ms 1.39% PetscLogEventEndDefault 0 0% 71.25% 57ms 1.36% VecDot_Seq 0 0% 71.25% 57ms 1.36% VecXDot_Seq_Private.constprop.0 56ms 1.34% 72.59% 56ms 1.34% [[vdso]] 0 0% 72.59% 56ms 1.34% __GI___clock_gettime 15ms 0.36% 72.94% 51ms 1.22% std::__cxx11::basic_string::basic_string 5ms 0.12% 73.06% 50ms 1.20% PetscLogEventBeginDefault 1ms 0.024% 73.09% 49ms 1.17% VecDuplicate_MPI 4ms 0.096% 73.18% 47ms 1.12% VecCopy 46ms 1.10% 74.28% 46ms 1.10% TimeStepper::CalculateNextTime 24ms 0.57% 74.86% 45ms 1.08% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 36ms 0.86% 75.72% 45ms 1.08% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.072% 75.79% 45ms 1.08% VecNorm 1ms 0.024% 75.81% 43ms 1.03% VecAXPY 43ms 1.03% 76.84% 43ms 1.03% VecMDot_Seq 8ms 0.19% 77.03% 42ms 1.00% AbstractCardiacCell::SetVoltage (partial-inline) 36ms 0.86% 77.89% 42ms 1.00% AbstractOneStepIvpOdeSolver::Solve 1ms 0.024% 77.92% 42ms 1.00% KSP_RemoveNullSpace (inline) 2ms 0.048% 77.96% 41ms 0.98% PetscMallocA 1ms 0.024% 77.99% 40ms 0.96% VecDestroy 1ms 0.024% 78.01% 40ms 0.96% VecMAXPY 15ms 0.36% 78.37% 39ms 0.93% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 78.37% 39ms 0.93% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.024% 78.39% 39ms 0.93% PetscMallocAlign 1ms 0.024% 78.42% 39ms 0.93% VecDuplicate_Seq 0 0% 78.42% 38ms 0.91% VecNorm_Seq 38ms 0.91% 79.33% 38ms 0.91% __ieee754_log_fma 38ms 0.91% 80.23% 38ms 0.91% std::isinf (inline) 2ms 0.048% 80.28% 37ms 0.88% VecCreate 34ms 0.81% 81.09% 34ms 0.81% [libchaste_heart.so] 34ms 0.81% 81.91% 34ms 0.81% __memcpy_avx_unaligned_erms 0 0% 81.91% 31ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 81.91% 31ms 0.74% PMPI_Init_thread 0 0% 81.91% 31ms 0.74% PetscInitialize 0 0% 81.91% 31ms 0.74% PetscSetup::setUpWorld 0 0% 81.91% 31ms 0.74% PetscSetupUtils::CommonSetup 0 0% 81.91% 31ms 0.74% PetscSetupUtils::InitialisePetsc 0 0% 81.91% 31ms 0.74% ompi_mpi_init 29ms 0.69% 82.60% 29ms 0.69% DistributedVector::Stripe::operator[] (inline) 2ms 0.048% 82.65% 28ms 0.67% VecAXPY_Seq 4ms 0.096% 82.74% 28ms 0.67% VecCreate_MPI_Private 10ms 0.24% 82.98% 28ms 0.67% top12 (inline) 0 0% 82.98% 27ms 0.65% PetscMemcpy (inline) 0 0% 82.98% 26ms 0.62% 25ms 0.6% 83.58% 26ms 0.62% VecMAXPY_Seq 0 0% 83.58% 26ms 0.62% VecNorm_MPI 0 0% 83.58% 26ms 0.62% VecNorm_MPI_Default.constprop.0 6ms 0.14% 83.72% 26ms 0.62% __GI___libc_malloc 0 0% 83.72% 26ms 0.62% __posix_memalign 2ms 0.048% 83.77% 26ms 0.62% std::char_traits::length (inline) 25ms 0.6% 84.37% 25ms 0.6% __strlen_avx2 22ms 0.53% 84.89% 24ms 0.57% AbstractParameterisedSystem::SetAnyVariable 2ms 0.048% 84.94% 24ms 0.57% PetscHeaderDestroy_Private 8ms 0.19% 85.13% 24ms 0.57% TimeStepper::AdvanceOneTimeStep 24ms 0.57% 85.71% 24ms 0.57% [libm.so.6] 22ms 0.53% 86.23% 22ms 0.53% DistributedVector::Iterator::operator!= 1ms 0.024% 86.26% 22ms 0.53% VecCopy_Seq (partial-inline) 22ms 0.53% 86.78% 22ms 0.53% daxpy_ 0 0% 86.78% 22ms 0.53% orte_init 0 0% 86.78% 21ms 0.5% AbstractFeVolumeIntegralAssembler::DoAssemble 18ms 0.43% 87.21% 21ms 0.5% AbstractParameterisedSystem::GetAnyVariable 7ms 0.17% 87.38% 21ms 0.5% PetscOptionsFindPair