File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3685ms, 85.98% of 4286ms total Dropped 484 nodes (cum <= 21.43ms) flat flat% sum% cum cum% 0 0% 0% 4277ms 99.79% __libc_start_call_main 0 0% 0% 4277ms 99.79% __libc_start_main_impl 0 0% 0% 4277ms 99.79% _start 0 0% 0% 4274ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4274ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4274ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4274ms 99.72% main 0 0% 0% 4231ms 98.72% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4231ms 98.72% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4231ms 98.72% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.023% 0.023% 4224ms 98.55% AbstractCardiacProblem::Solve 0 0% 0.023% 4207ms 98.16% AbstractDynamicLinearPdeSolver::Solve 9ms 0.21% 0.23% 2216ms 51.70% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.26% 1651ms 38.52% LinearSystem::Solve 0 0% 0.26% 1600ms 37.33% KSPSolve 3ms 0.07% 0.33% 1600ms 37.33% KSPSolve_Private 5ms 0.12% 0.44% 1541ms 35.95% KSPSolve_SYMMLQ 29ms 0.68% 1.12% 1272ms 29.68% AbstractCardiacCell::ComputeExceptVoltage 27ms 0.63% 1.75% 1100ms 25.66% AbstractOneStepIvpOdeSolver::InternalSolve 3ms 0.07% 1.82% 980ms 22.87% PCApply 2ms 0.047% 1.87% 968ms 22.59% PCApply_BJacobi_Singleblock 55ms 1.28% 3.15% 914ms 21.33% EulerIvpOdeSolver::CalculateNextYValue 228ms 5.32% 8.47% 851ms 19.86% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 8.47% 822ms 19.18% KSPSolve_PREONLY (partial-inline) 364ms 8.49% 16.96% 784ms 18.29% __GI___exp 27ms 0.63% 17.59% 783ms 18.27% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.023% 17.62% 771ms 17.99% PCApply_ICC 1ms 0.023% 17.64% 770ms 17.97% MatSolve 760ms 17.73% 35.37% 763ms 17.80% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 35.37% 727ms 16.96% KSP_PCApply.lto_priv.18 241ms 5.62% 40.99% 725ms 16.92% CellLuoRudy1991FromCellML::GetIIonic 7ms 0.16% 41.16% 427ms 9.96% MatMult 16ms 0.37% 41.53% 415ms 9.68% MatMult_SeqAIJ (partial-inline) 395ms 9.22% 50.75% 414ms 9.66% MatMult_SeqAIJ_Inode (inline) 333ms 7.77% 58.52% 396ms 9.24% __ieee754_exp_fma 1ms 0.023% 58.54% 340ms 7.93% KSP_PCApply (partial-inline) 1ms 0.023% 58.56% 336ms 7.84% KSPConvergedDefault 59ms 1.38% 59.94% 297ms 6.93% BidomainSolver::SetupLinearSystem 1ms 0.023% 59.96% 240ms 5.60% std::pow (inline) 18ms 0.42% 60.38% 239ms 5.58% __pow 21ms 0.49% 60.87% 220ms 5.13% __ieee754_pow_fma 1ms 0.023% 60.90% 135ms 3.15% MatNullSpaceRemove 116ms 2.71% 63.60% 116ms 2.71% log_inline (inline) 53ms 1.24% 64.84% 108ms 2.52% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 64.89% 89ms 2.08% VecDuplicate 25ms 0.58% 65.47% 82ms 1.91% MPI_Wtime 1ms 0.023% 65.49% 77ms 1.80% VecMDot 5ms 0.12% 65.61% 76ms 1.77% VecMXDot_Private 33ms 0.77% 66.38% 73ms 1.70% TimeStepper::TimeStepper 70ms 1.63% 68.01% 70ms 1.63% ddot_ 69ms 1.61% 69.62% 70ms 1.63% exp_inline (inline) 1ms 0.023% 69.65% 67ms 1.56% VecDestroy 12ms 0.28% 69.93% 63ms 1.47% PetscLogEventEndDefault 10ms 0.23% 70.16% 59ms 1.38% AbstractCardiacCell::SetVoltage (partial-inline) 4ms 0.093% 70.25% 58ms 1.35% VecCopy 10ms 0.23% 70.49% 57ms 1.33% PetscLogEventBeginDefault 3ms 0.07% 70.56% 57ms 1.33% VecMAXPY 1ms 0.023% 70.58% 57ms 1.33% __GI___clock_gettime 56ms 1.31% 71.89% 56ms 1.31% [[vdso]] 55ms 1.28% 73.17% 55ms 1.28% [libchaste_heart.so] 1ms 0.023% 73.19% 54ms 1.26% VecDot 2ms 0.047% 73.24% 49ms 1.14% VecNorm 0 0% 73.24% 48ms 1.12% VecDot_Seq 0 0% 73.24% 48ms 1.12% VecXDot_Seq_Private.constprop.0 40ms 0.93% 74.17% 47ms 1.10% top12 (inline) 45ms 1.05% 75.22% 46ms 1.07% VecMDot_Seq 46ms 1.07% 76.29% 46ms 1.07% __memcpy_avx_unaligned_erms 6ms 0.14% 76.43% 44ms 1.03% PetscMallocA 1ms 0.023% 76.46% 44ms 1.03% VecDuplicate_Seq 41ms 0.96% 77.41% 44ms 1.03% __ieee754_log_fma 34ms 0.79% 78.21% 43ms 1.00% AbstractParameterisedSystem::SetAnyVariable 1ms 0.023% 78.23% 43ms 1.00% VecDuplicate_MPI 41ms 0.96% 79.19% 41ms 0.96% TimeStepper::CalculateNextTime 3ms 0.07% 79.26% 40ms 0.93% VecAXPY 40ms 0.93% 80.19% 40ms 0.93% asuint64 (inline) 1ms 0.023% 80.21% 39ms 0.91% VecNorm_Seq 0 0% 80.21% 38ms 0.89% CxxTest::RealWorldDescription::setUp 0 0% 80.21% 38ms 0.89% PetscInitialize 3ms 0.07% 80.28% 38ms 0.89% PetscMallocAlign 0 0% 80.28% 38ms 0.89% PetscSetup::setUpWorld 0 0% 80.28% 38ms 0.89% PetscSetupUtils::CommonSetup 0 0% 80.28% 38ms 0.89% PetscSetupUtils::InitialisePetsc 0 0% 80.28% 37ms 0.86% PMPI_Init_thread 0 0% 80.28% 37ms 0.86% ompi_mpi_init 1ms 0.023% 80.31% 36ms 0.84% PetscTimeSubtract (inline) 0 0% 80.31% 36ms 0.84% VecCreate 35ms 0.82% 81.12% 36ms 0.84% VecMAXPY_Seq 9ms 0.21% 81.33% 35ms 0.82% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.33% 35ms 0.82% AbstractBidomainSolver::FinaliseLinearSystem 31ms 0.72% 82.06% 35ms 0.82% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 27ms 0.63% 82.69% 33ms 0.77% AbstractOneStepIvpOdeSolver::Solve 0 0% 82.69% 33ms 0.77% PetscMemcpy (inline) 3ms 0.07% 82.76% 32ms 0.75% PetscHeaderDestroy_Private 2ms 0.047% 82.80% 29ms 0.68% 0 0% 82.80% 29ms 0.68% VecCopy_Seq (partial-inline) 14ms 0.33% 83.13% 28ms 0.65% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 83.13% 27ms 0.63% VecAXPY_Seq 15ms 0.35% 83.48% 26ms 0.61% TimeStepper::AdvanceOneTimeStep 26ms 0.61% 84.09% 26ms 0.61% [libm.so.6] 26ms 0.61% 84.69% 26ms 0.61% daxpy_ 0 0% 84.69% 25ms 0.58% VecNorm_MPI 0 0% 84.69% 25ms 0.58% VecNorm_MPI_Default.constprop.0 0 0% 84.69% 24ms 0.56% orte_init 15ms 0.35% 85.04% 24ms 0.56% std::__cxx11::basic_string::basic_string 23ms 0.54% 85.58% 23ms 0.54% TimeStepper::GetNextTimeStep 6ms 0.14% 85.72% 22ms 0.51% PetscFreeAlign 2ms 0.047% 85.77% 22ms 0.51% VecCreate_MPI_Private 0 0% 85.77% 22ms 0.51% VecSetType 9ms 0.21% 85.98% 22ms 0.51% __GI___libc_free 0 0% 85.98% 22ms 0.51% std::vector::_M_assign_aux (inline) 0 0% 85.98% 22ms 0.51% std::vector::assign (inline)