File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3605ms, 85.96% of 4194ms total Dropped 469 nodes (cum <= 20.97ms) flat flat% sum% cum cum% 0 0% 0% 4187ms 99.83% __libc_start_call_main 0 0% 0% 4187ms 99.83% __libc_start_main_impl 0 0% 0% 4187ms 99.83% _start 0 0% 0% 4184ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4184ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4184ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4184ms 99.76% main 0 0% 0% 4145ms 98.83% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4145ms 98.83% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4145ms 98.83% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4137ms 98.64% AbstractCardiacProblem::Solve 2ms 0.048% 0.048% 4128ms 98.43% AbstractDynamicLinearPdeSolver::Solve 14ms 0.33% 0.38% 2270ms 54.12% AbstractCardiacTissue::SolveCellSystems 2ms 0.048% 0.43% 1518ms 36.19% LinearSystem::Solve 0 0% 0.43% 1471ms 35.07% KSPSolve 5ms 0.12% 0.55% 1471ms 35.07% KSPSolve_Private 0 0% 0.55% 1418ms 33.81% KSPSolve_SYMMLQ 43ms 1.03% 1.57% 1320ms 31.47% AbstractCardiacCell::ComputeExceptVoltage 15ms 0.36% 1.93% 1105ms 26.35% AbstractOneStepIvpOdeSolver::InternalSolve 73ms 1.74% 3.67% 923ms 22.01% EulerIvpOdeSolver::CalculateNextYValue 5ms 0.12% 3.79% 880ms 20.98% PCApply 0 0% 3.79% 868ms 20.70% PCApply_BJacobi_Singleblock 243ms 5.79% 9.59% 837ms 19.96% CellLuoRudy1991FromCellML::EvaluateYDerivatives 338ms 8.06% 17.64% 753ms 17.95% __GI___exp 22ms 0.52% 18.17% 745ms 17.76% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 18.17% 718ms 17.12% KSPSolve_PREONLY (partial-inline) 251ms 5.98% 24.15% 696ms 16.60% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 24.18% 681ms 16.24% MatSolve 0 0% 24.18% 681ms 16.24% PCApply_ICC 672ms 16.02% 40.20% 676ms 16.12% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.20% 664ms 15.83% KSP_PCApply.lto_priv.18 2ms 0.048% 40.25% 429ms 10.23% MatMult 2ms 0.048% 40.30% 422ms 10.06% MatMult_SeqAIJ 414ms 9.87% 50.17% 422ms 10.06% MatMult_SeqAIJ_Inode (inline) 378ms 9.01% 59.18% 397ms 9.47% __ieee754_exp_fma 1ms 0.024% 59.20% 309ms 7.37% KSPConvergedDefault 59ms 1.41% 60.61% 296ms 7.06% BidomainSolver::SetupLinearSystem 0 0% 60.61% 292ms 6.96% KSP_PCApply (partial-inline) 2ms 0.048% 60.66% 212ms 5.05% std::pow (inline) 17ms 0.41% 61.06% 210ms 5.01% __pow 23ms 0.55% 61.61% 193ms 4.60% __ieee754_pow_fma 71ms 1.69% 63.30% 154ms 3.67% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 63.33% 119ms 2.84% MatNullSpaceRemove 0 0% 63.33% 116ms 2.77% VecDuplicate 91ms 2.17% 65.50% 92ms 2.19% exp_inline (inline) 26ms 0.62% 66.12% 82ms 1.96% MPI_Wtime 47ms 1.12% 67.24% 80ms 1.91% TimeStepper::TimeStepper 75ms 1.79% 69.03% 75ms 1.79% log_inline (inline) 0 0% 69.03% 70ms 1.67% VecMDot 3ms 0.072% 69.10% 70ms 1.67% VecMXDot_Private 66ms 1.57% 70.67% 66ms 1.57% ddot_ 9ms 0.21% 70.89% 61ms 1.45% PetscLogEventBeginDefault 4ms 0.095% 70.98% 60ms 1.43% VecCopy 11ms 0.26% 71.24% 59ms 1.41% PetscLogEventEndDefault 0 0% 71.24% 59ms 1.41% VecDuplicate_Seq 1ms 0.024% 71.27% 57ms 1.36% VecDuplicate_MPI 56ms 1.34% 72.60% 56ms 1.34% [[vdso]] 0 0% 72.60% 56ms 1.34% __GI___clock_gettime 43ms 1.03% 73.63% 54ms 1.29% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 52ms 1.24% 74.87% 53ms 1.26% __ieee754_log_fma 0 0% 74.87% 50ms 1.19% VecDot 4ms 0.095% 74.96% 48ms 1.14% VecMAXPY 6ms 0.14% 75.11% 47ms 1.12% PetscMallocA 0 0% 75.11% 45ms 1.07% VecDot_Seq 45ms 1.07% 76.18% 45ms 1.07% VecMDot_Seq 0 0% 76.18% 45ms 1.07% VecXDot_Seq_Private.constprop.0 44ms 1.05% 77.23% 44ms 1.05% [libchaste_heart.so] 21ms 0.5% 77.73% 42ms 1.00% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 1ms 0.024% 77.75% 42ms 1.00% VecNorm 5ms 0.12% 77.87% 41ms 0.98% PetscMallocAlign (partial-inline) 0 0% 77.87% 41ms 0.98% VecCreate 3ms 0.072% 77.94% 40ms 0.95% AbstractCardiacCell::SetVoltage (partial-inline) 40ms 0.95% 78.90% 40ms 0.95% TimeStepper::CalculateNextTime 40ms 0.95% 79.85% 40ms 0.95% std::isinf (inline) 3ms 0.072% 79.92% 38ms 0.91% VecAXPY 2ms 0.048% 79.97% 38ms 0.91% VecSetType 29ms 0.69% 80.66% 37ms 0.88% AbstractOneStepIvpOdeSolver::Solve 1ms 0.024% 80.69% 36ms 0.86% VecDestroy 14ms 0.33% 81.02% 35ms 0.83% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.02% 35ms 0.83% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 81.02% 34ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 81.02% 34ms 0.81% PetscInitialize 0 0% 81.02% 34ms 0.81% PetscSetup::setUpWorld 0 0% 81.02% 34ms 0.81% PetscSetupUtils::CommonSetup 0 0% 81.02% 34ms 0.81% PetscSetupUtils::InitialisePetsc 0 0% 81.02% 34ms 0.81% VecNorm_Seq 26ms 0.62% 81.64% 33ms 0.79% AbstractParameterisedSystem::SetAnyVariable 0 0% 81.64% 33ms 0.79% PMPI_Init_thread 0 0% 81.64% 33ms 0.79% ompi_mpi_init 0 0% 81.64% 32ms 0.76% PetscMemcpy (inline) 3ms 0.072% 81.71% 32ms 0.76% VecCreate_MPI_Private 31ms 0.74% 82.45% 31ms 0.74% __memcpy_avx_unaligned_erms 0 0% 82.45% 30ms 0.72% PetscMemzero (inline) 29ms 0.69% 83.14% 29ms 0.69% __memset_avx2_unaligned_erms 1ms 0.024% 83.17% 28ms 0.67% VecCreate_Seq 26ms 0.62% 83.79% 28ms 0.67% VecMAXPY_Seq 1ms 0.024% 83.81% 27ms 0.64% 0 0% 83.81% 27ms 0.64% VecAXPY_Seq 26ms 0.62% 84.43% 26ms 0.62% DistributedVector::Stripe::operator[] (inline) 1ms 0.024% 84.45% 26ms 0.62% VecCopy_Seq (partial-inline) 26ms 0.62% 85.07% 26ms 0.62% daxpy_ 15ms 0.36% 85.43% 26ms 0.62% std::__cxx11::basic_string::basic_string 11ms 0.26% 85.69% 23ms 0.55% top12 (inline) 0 0% 85.69% 22ms 0.52% VecNorm_MPI 1ms 0.024% 85.72% 22ms 0.52% VecNorm_MPI_Default.constprop.0 0 0% 85.72% 22ms 0.52% orte_init 0 0% 85.72% 21ms 0.5% AbstractFeVolumeIntegralAssembler::DoAssemble 10ms 0.24% 85.96% 21ms 0.5% TimeStepper::AdvanceOneTimeStep