File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3588ms, 86.48% of 4149ms total Dropped 462 nodes (cum <= 20.75ms) flat flat% sum% cum cum% 0 0% 0% 4141ms 99.81% __libc_start_call_main 0 0% 0% 4141ms 99.81% __libc_start_main_impl 0 0% 0% 4141ms 99.81% _start 0 0% 0% 4138ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4138ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4138ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4138ms 99.73% main 0 0% 0% 4097ms 98.75% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4097ms 98.75% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4097ms 98.75% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4089ms 98.55% AbstractCardiacProblem::Solve 0 0% 0% 4078ms 98.29% AbstractDynamicLinearPdeSolver::Solve 14ms 0.34% 0.34% 2543ms 61.29% AbstractCardiacTissue::SolveCellSystems 31ms 0.75% 1.08% 1455ms 35.07% AbstractCardiacCell::ComputeExceptVoltage 19ms 0.46% 1.54% 1231ms 29.67% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.024% 1.57% 1172ms 28.25% LinearSystem::Solve 0 0% 1.57% 1125ms 27.11% KSPSolve 5ms 0.12% 1.69% 1125ms 27.11% KSPSolve_Private 2ms 0.048% 1.74% 1090ms 26.27% KSPSolve_SYMMLQ 41ms 0.99% 2.72% 1054ms 25.40% EulerIvpOdeSolver::CalculateNextYValue 283ms 6.82% 9.54% 997ms 24.03% CellLuoRudy1991FromCellML::EvaluateYDerivatives 403ms 9.71% 19.26% 883ms 21.28% __GI___exp 33ms 0.8% 20.05% 818ms 19.72% AbstractCardiacTissue::UpdateCaches (inline) 258ms 6.22% 26.27% 756ms 18.22% CellLuoRudy1991FromCellML::GetIIonic 0 0% 26.27% 670ms 16.15% PCApply 3ms 0.072% 26.34% 664ms 16.00% PCApply_BJacobi_Singleblock 0 0% 26.34% 538ms 12.97% KSPSolve_PREONLY (partial-inline) 2ms 0.048% 26.39% 515ms 12.41% MatSolve 0 0% 26.39% 515ms 12.41% PCApply_ICC 506ms 12.20% 38.59% 510ms 12.29% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 38.59% 507ms 12.22% KSP_PCApply.lto_priv.18 422ms 10.17% 48.76% 449ms 10.82% __ieee754_exp_fma 5ms 0.12% 48.88% 409ms 9.86% MatMult 8ms 0.19% 49.07% 400ms 9.64% MatMult_SeqAIJ 387ms 9.33% 58.40% 393ms 9.47% MatMult_SeqAIJ_Inode (inline) 57ms 1.37% 59.77% 314ms 7.57% BidomainSolver::SetupLinearSystem 27ms 0.65% 60.42% 251ms 6.05% __pow 0 0% 60.42% 251ms 6.05% std::pow (inline) 30ms 0.72% 61.15% 223ms 5.37% __ieee754_pow_fma 1ms 0.024% 61.17% 219ms 5.28% KSPConvergedDefault 87ms 2.10% 63.27% 213ms 5.13% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 63.27% 200ms 4.82% KSP_PCApply (partial-inline) 104ms 2.51% 65.77% 104ms 2.51% log_inline (inline) 47ms 1.13% 66.91% 83ms 2.00% TimeStepper::TimeStepper 1ms 0.024% 66.93% 81ms 1.95% MatNullSpaceRemove 77ms 1.86% 68.79% 77ms 1.86% exp_inline (inline) 0 0% 68.79% 76ms 1.83% VecDuplicate 74ms 1.78% 70.57% 74ms 1.78% std::isinf (inline) 17ms 0.41% 70.98% 71ms 1.71% MPI_Wtime 56ms 1.35% 72.33% 56ms 1.35% __ieee754_log_fma 44ms 1.06% 73.39% 55ms 1.33% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 6ms 0.14% 73.54% 54ms 1.30% PetscLogEventBeginDefault 54ms 1.30% 74.84% 54ms 1.30% [[vdso]] 0 0% 74.84% 54ms 1.30% __GI___clock_gettime 41ms 0.99% 75.83% 51ms 1.23% AbstractOneStepIvpOdeSolver::Solve 13ms 0.31% 76.14% 49ms 1.18% PetscLogEventEndDefault 28ms 0.67% 76.81% 48ms 1.16% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 5ms 0.12% 76.93% 48ms 1.16% VecCopy 2ms 0.048% 76.98% 46ms 1.11% VecDuplicate_MPI 1ms 0.024% 77.01% 45ms 1.08% VecMDot 24ms 0.58% 77.58% 44ms 1.06% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 77.58% 44ms 1.06% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.024% 77.61% 44ms 1.06% VecMXDot_Private 43ms 1.04% 78.65% 43ms 1.04% TimeStepper::CalculateNextTime 42ms 1.01% 79.66% 42ms 1.01% ddot_ 17ms 0.41% 80.07% 41ms 0.99% std::__cxx11::basic_string::basic_string 1ms 0.024% 80.09% 39ms 0.94% VecNorm 7ms 0.17% 80.26% 38ms 0.92% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 80.26% 37ms 0.89% CxxTest::RealWorldDescription::setUp 1ms 0.024% 80.28% 37ms 0.89% PMPI_Init_thread 0 0% 80.28% 37ms 0.89% PetscInitialize 0 0% 80.28% 37ms 0.89% PetscSetup::setUpWorld 0 0% 80.28% 37ms 0.89% PetscSetupUtils::CommonSetup 0 0% 80.28% 37ms 0.89% PetscSetupUtils::InitialisePetsc 0 0% 80.28% 36ms 0.87% ompi_mpi_init 21ms 0.51% 80.79% 36ms 0.87% top12 (inline) 2ms 0.048% 80.84% 35ms 0.84% VecMAXPY 0 0% 80.84% 32ms 0.77% VecNorm_Seq 32ms 0.77% 81.61% 32ms 0.77% [libm.so.6] 0 0% 81.61% 31ms 0.75% 3ms 0.072% 81.68% 31ms 0.75% VecAXPY 2ms 0.048% 81.73% 31ms 0.75% VecDot 0 0% 81.73% 30ms 0.72% PetscMemcpy (inline) 1ms 0.024% 81.75% 30ms 0.72% VecDuplicate_Seq 30ms 0.72% 82.48% 30ms 0.72% [libchaste_heart.so] 26ms 0.63% 83.10% 29ms 0.7% AbstractParameterisedSystem::SetAnyVariable 1ms 0.024% 83.13% 29ms 0.7% VecCreate_MPI_Private 28ms 0.67% 83.80% 29ms 0.7% VecMDot_Seq 29ms 0.7% 84.50% 29ms 0.7% __memcpy_avx_unaligned_erms 1ms 0.024% 84.53% 28ms 0.67% VecDestroy 3ms 0.072% 84.60% 27ms 0.65% PetscMallocA 13ms 0.31% 84.91% 27ms 0.65% TimeStepper::AdvanceOneTimeStep 0 0% 84.91% 27ms 0.65% VecNorm_MPI 1ms 0.024% 84.94% 27ms 0.65% VecNorm_MPI_Default.constprop.0 4ms 0.096% 85.03% 26ms 0.63% PetscOptionsFindPair 3ms 0.072% 85.10% 25ms 0.6% VecCreate 0 0% 85.10% 25ms 0.6% VecDot_Seq 0 0% 85.10% 25ms 0.6% VecXDot_Seq_Private.constprop.0 2ms 0.048% 85.15% 24ms 0.58% PetscMallocAlign 0 0% 85.15% 24ms 0.58% orte_init 23ms 0.55% 85.71% 23ms 0.55% DistributedVector::Iterator::operator!= 11ms 0.27% 85.97% 22ms 0.53% AbstractCardiacCell::GetVoltage (partial-inline) 19ms 0.46% 86.43% 22ms 0.53% AbstractParameterisedSystem::GetAnyVariable 0 0% 86.43% 22ms 0.53% KSP_RemoveNullSpace (inline) 2ms 0.048% 86.48% 22ms 0.53% VecCopy_Seq (partial-inline)