File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3730ms, 87.17% of 4279ms total Dropped 471 nodes (cum <= 21.39ms) flat flat% sum% cum cum% 0 0% 0% 4268ms 99.74% __libc_start_call_main 0 0% 0% 4268ms 99.74% __libc_start_main_impl 0 0% 0% 4268ms 99.74% _start 0 0% 0% 4264ms 99.65% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4264ms 99.65% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4264ms 99.65% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4264ms 99.65% main 0 0% 0% 4223ms 98.69% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4223ms 98.69% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4223ms 98.69% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 2ms 0.047% 0.047% 4215ms 98.50% AbstractCardiacProblem::Solve 3ms 0.07% 0.12% 4198ms 98.11% AbstractDynamicLinearPdeSolver::Solve 19ms 0.44% 0.56% 2220ms 51.88% AbstractCardiacTissue::SolveCellSystems 0 0% 0.56% 1576ms 36.83% LinearSystem::Solve 0 0% 0.56% 1518ms 35.48% KSPSolve 2ms 0.047% 0.61% 1518ms 35.48% KSPSolve_Private 0 0% 0.61% 1465ms 34.24% KSPSolve_SYMMLQ 24ms 0.56% 1.17% 1276ms 29.82% AbstractCardiacCell::ComputeExceptVoltage 34ms 0.79% 1.96% 1096ms 25.61% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.047% 2.01% 926ms 21.64% PCApply 0 0% 2.01% 916ms 21.41% PCApply_BJacobi_Singleblock 70ms 1.64% 3.65% 910ms 21.27% EulerIvpOdeSolver::CalculateNextYValue 227ms 5.30% 8.95% 838ms 19.58% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 8.95% 793ms 18.53% KSPSolve_PREONLY (partial-inline) 384ms 8.97% 17.92% 763ms 17.83% __GI___exp 34ms 0.79% 18.72% 758ms 17.71% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.023% 18.74% 745ms 17.41% MatSolve 0 0% 18.74% 745ms 17.41% PCApply_ICC 738ms 17.25% 35.99% 739ms 17.27% MatSolve_SeqSBAIJ_1_NaturalOrdering 269ms 6.29% 42.28% 693ms 16.20% CellLuoRudy1991FromCellML::GetIIonic 0 0% 42.28% 683ms 15.96% KSP_PCApply.lto_priv.18 4ms 0.093% 42.37% 439ms 10.26% MatMult 14ms 0.33% 42.70% 422ms 9.86% MatMult_SeqAIJ (partial-inline) 406ms 9.49% 52.19% 421ms 9.84% MatMult_SeqAIJ_Inode (inline) 299ms 6.99% 59.17% 357ms 8.34% __ieee754_exp_fma 1ms 0.023% 59.20% 343ms 8.02% KSPConvergedDefault 59ms 1.38% 60.57% 334ms 7.81% BidomainSolver::SetupLinearSystem 0 0% 60.57% 333ms 7.78% KSP_PCApply (partial-inline) 18ms 0.42% 61.00% 206ms 4.81% __pow 0 0% 61.00% 206ms 4.81% std::pow (inline) 18ms 0.42% 61.42% 188ms 4.39% __ieee754_pow_fma 0 0% 61.42% 146ms 3.41% MatNullSpaceRemove 53ms 1.24% 62.65% 109ms 2.55% CellLuoRudy1991FromCellML::VerifyStateVariables 99ms 2.31% 64.97% 99ms 2.31% log_inline (inline) 17ms 0.4% 65.37% 92ms 2.15% MPI_Wtime 0 0% 65.37% 92ms 2.15% VecMDot 4ms 0.093% 65.46% 92ms 2.15% VecMXDot_Private 0 0% 65.46% 85ms 1.99% VecDuplicate 18ms 0.42% 65.88% 81ms 1.89% PetscLogEventEndDefault 2ms 0.047% 65.93% 75ms 1.75% __GI___clock_gettime 74ms 1.73% 67.66% 74ms 1.73% [[vdso]] 69ms 1.61% 69.27% 69ms 1.61% ddot_ 27ms 0.63% 69.90% 67ms 1.57% TimeStepper::TimeStepper 12ms 0.28% 70.18% 66ms 1.54% VecCopy 63ms 1.47% 71.65% 66ms 1.54% exp_inline (inline) 2ms 0.047% 71.70% 65ms 1.52% KSP_RemoveNullSpace (inline) 9ms 0.21% 71.91% 63ms 1.47% PetscLogEventBeginDefault 22ms 0.51% 72.42% 61ms 1.43% AbstractCardiacCell::SetVoltage (partial-inline) 56ms 1.31% 73.73% 57ms 1.33% VecMDot_Seq 1ms 0.023% 73.76% 55ms 1.29% AbstractBidomainSolver::FinaliseLinearSystem 5ms 0.12% 73.87% 55ms 1.29% VecDuplicate_MPI 9ms 0.21% 74.08% 54ms 1.26% AbstractBidomainSolver::CheckCompatibilityCondition 4ms 0.093% 74.18% 54ms 1.26% VecMAXPY 53ms 1.24% 75.41% 53ms 1.24% [libchaste_heart.so] 1ms 0.023% 75.44% 52ms 1.22% VecDot 4ms 0.093% 75.53% 49ms 1.15% VecAXPY 1ms 0.023% 75.56% 47ms 1.10% VecNorm 46ms 1.08% 76.63% 46ms 1.08% DistributedVector::Iterator::operator!= 1ms 0.023% 76.65% 46ms 1.08% VecDestroy 0 0% 76.65% 45ms 1.05% VecDot_Seq 1ms 0.023% 76.68% 45ms 1.05% VecXDot_Seq_Private.constprop.0 33ms 0.77% 77.45% 45ms 1.05% top12 (inline) 20ms 0.47% 77.92% 43ms 1.00% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 77.92% 41ms 0.96% VecNorm_Seq 40ms 0.93% 78.85% 40ms 0.93% TimeStepper::CalculateNextTime 38ms 0.89% 79.74% 38ms 0.89% __memcpy_avx_unaligned_erms 0 0% 79.74% 37ms 0.86% CxxTest::RealWorldDescription::setUp 0 0% 79.74% 37ms 0.86% PetscInitialize 0 0% 79.74% 37ms 0.86% PetscSetup::setUpWorld 0 0% 79.74% 37ms 0.86% PetscSetupUtils::CommonSetup 0 0% 79.74% 37ms 0.86% PetscSetupUtils::InitialisePetsc 0 0% 79.74% 36ms 0.84% PMPI_Init_thread 3ms 0.07% 79.81% 36ms 0.84% PetscMallocA 0 0% 79.81% 36ms 0.84% ompi_mpi_init 27ms 0.63% 80.44% 35ms 0.82% AbstractParameterisedSystem::SetAnyVariable 35ms 0.82% 81.26% 35ms 0.82% VecMAXPY_Seq 29ms 0.68% 81.94% 34ms 0.79% AbstractOneStepIvpOdeSolver::Solve 34ms 0.79% 82.73% 34ms 0.79% asuint64 (inline) 3ms 0.07% 82.80% 33ms 0.77% PetscMallocAlign 3ms 0.07% 82.87% 32ms 0.75% 30ms 0.7% 83.57% 32ms 0.75% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.07% 83.64% 32ms 0.75% VecCreate 0 0% 83.64% 31ms 0.72% PetscMemcpy (inline) 1ms 0.023% 83.66% 30ms 0.7% VecDuplicate_Seq 29ms 0.68% 84.34% 30ms 0.7% __ieee754_log_fma 1ms 0.023% 84.37% 29ms 0.68% VecCopy_Seq (partial-inline) 2ms 0.047% 84.41% 27ms 0.63% VecAXPY_Seq 0 0% 84.41% 27ms 0.63% VecNorm_MPI 0 0% 84.41% 27ms 0.63% VecNorm_MPI_Default.constprop.0 19ms 0.44% 84.86% 26ms 0.61% AbstractParameterisedSystem::GetAnyVariable 0 0% 84.86% 26ms 0.61% orte_init 2ms 0.047% 84.90% 25ms 0.58% VecCreate_MPI_Private 15ms 0.35% 85.25% 24ms 0.56% TimeStepper::AdvanceOneTimeStep 13ms 0.3% 85.56% 23ms 0.54% std::__cxx11::basic_string::basic_string 22ms 0.51% 86.07% 22ms 0.51% TimeStepper::GetNextTimeStep 22ms 0.51% 86.59% 22ms 0.51% [libm.so.6] 3ms 0.07% 86.66% 22ms 0.51% __GI___libc_free 22ms 0.51% 87.17% 22ms 0.51% daxpy_