File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3624ms, 87.16% of 4158ms total Dropped 465 nodes (cum <= 20.79ms) flat flat% sum% cum cum% 0 0% 0% 4151ms 99.83% __libc_start_call_main 0 0% 0% 4151ms 99.83% __libc_start_main_impl 0 0% 0% 4151ms 99.83% _start 0 0% 0% 4148ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4148ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4148ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4148ms 99.76% main 0 0% 0% 4111ms 98.87% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4111ms 98.87% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4110ms 98.85% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4102ms 98.65% AbstractCardiacProblem::Solve 0 0% 0% 4089ms 98.34% AbstractDynamicLinearPdeSolver::Solve 20ms 0.48% 0.48% 2456ms 59.07% AbstractCardiacTissue::SolveCellSystems 42ms 1.01% 1.49% 1420ms 34.15% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.49% 1283ms 30.86% LinearSystem::Solve 0 0% 1.49% 1250ms 30.06% KSPSolve 4ms 0.096% 1.59% 1250ms 30.06% KSPSolve_Private 29ms 0.7% 2.28% 1212ms 29.15% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.048% 2.33% 1205ms 28.98% KSPSolve_SYMMLQ 64ms 1.54% 3.87% 1004ms 24.15% EulerIvpOdeSolver::CalculateNextYValue 271ms 6.52% 10.39% 929ms 22.34% CellLuoRudy1991FromCellML::EvaluateYDerivatives 375ms 9.02% 19.41% 822ms 19.77% __GI___exp 30ms 0.72% 20.13% 791ms 19.02% AbstractCardiacTissue::UpdateCaches (inline) 270ms 6.49% 26.62% 730ms 17.56% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 26.65% 694ms 16.69% PCApply 3ms 0.072% 26.72% 690ms 16.59% PCApply_BJacobi_Singleblock 0 0% 26.72% 557ms 13.40% KSPSolve_PREONLY (partial-inline) 2ms 0.048% 26.77% 528ms 12.70% MatSolve 0 0% 26.77% 528ms 12.70% PCApply_ICC 515ms 12.39% 39.15% 520ms 12.51% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 1ms 0.024% 39.18% 519ms 12.48% KSP_PCApply.lto_priv.18 6ms 0.14% 39.32% 439ms 10.56% MatMult 85ms 2.04% 41.37% 429ms 10.32% MatMult_SeqAIJ 394ms 9.48% 50.84% 426ms 10.25% __ieee754_exp_fma 341ms 8.20% 59.04% 346ms 8.32% MatMult_SeqAIJ_Inode (inline) 42ms 1.01% 60.05% 293ms 7.05% BidomainSolver::SetupLinearSystem 4ms 0.096% 60.15% 254ms 6.11% KSPConvergedDefault 26ms 0.63% 60.77% 236ms 5.68% __pow 0 0% 60.77% 236ms 5.68% std::pow (inline) 0 0% 60.77% 231ms 5.56% KSP_PCApply (partial-inline) 35ms 0.84% 61.62% 209ms 5.03% __ieee754_pow_fma 69ms 1.66% 63.28% 172ms 4.14% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 63.28% 99ms 2.38% MatNullSpaceRemove 97ms 2.33% 65.61% 97ms 2.33% log_inline (inline) 1ms 0.024% 65.63% 94ms 2.26% VecDuplicate 25ms 0.6% 66.23% 82ms 1.97% MPI_Wtime 40ms 0.96% 67.20% 81ms 1.95% TimeStepper::TimeStepper 76ms 1.83% 69.02% 76ms 1.83% ddot_ 69ms 1.66% 70.68% 70ms 1.68% exp_inline (inline) 0 0% 70.68% 66ms 1.59% VecMDot 3ms 0.072% 70.76% 66ms 1.59% VecMXDot_Private 1ms 0.024% 70.78% 57ms 1.37% __GI___clock_gettime 56ms 1.35% 72.13% 56ms 1.35% [[vdso]] 5ms 0.12% 72.25% 54ms 1.30% PetscLogEventBeginDefault 2ms 0.048% 72.29% 51ms 1.23% VecDot 11ms 0.26% 72.56% 50ms 1.20% PetscLogEventEndDefault 1ms 0.024% 72.58% 49ms 1.18% AbstractBidomainSolver::FinaliseLinearSystem 10ms 0.24% 72.82% 48ms 1.15% AbstractBidomainSolver::CheckCompatibilityCondition 2ms 0.048% 72.87% 48ms 1.15% VecDuplicate_Seq 2ms 0.048% 72.92% 47ms 1.13% VecNorm 47ms 1.13% 74.05% 47ms 1.13% std::isinf (inline) 46ms 1.11% 75.16% 46ms 1.11% TimeStepper::CalculateNextTime 2ms 0.048% 75.20% 46ms 1.11% VecCopy 0 0% 75.20% 45ms 1.08% VecDot_Seq 0 0% 75.20% 45ms 1.08% VecDuplicate_MPI 0 0% 75.20% 45ms 1.08% VecXDot_Seq_Private.constprop.0 10ms 0.24% 75.44% 44ms 1.06% AbstractCardiacCell::SetVoltage (partial-inline) 44ms 1.06% 76.50% 44ms 1.06% __memcpy_avx_unaligned_erms 42ms 1.01% 77.51% 43ms 1.03% VecMDot_Seq 33ms 0.79% 78.31% 41ms 0.99% AbstractOneStepIvpOdeSolver::Solve 34ms 0.82% 79.12% 40ms 0.96% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 29ms 0.7% 79.82% 39ms 0.94% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 1ms 0.024% 79.85% 39ms 0.94% VecNorm_Seq 38ms 0.91% 80.76% 39ms 0.94% __ieee754_log_fma 1ms 0.024% 80.78% 38ms 0.91% KSP_RemoveNullSpace (inline) 13ms 0.31% 81.10% 37ms 0.89% top12 (inline) 5ms 0.12% 81.22% 35ms 0.84% PetscMallocA 1ms 0.024% 81.24% 34ms 0.82% VecCreate 0 0% 81.24% 33ms 0.79% VecDestroy 3ms 0.072% 81.31% 33ms 0.79% VecMAXPY 0 0% 81.31% 32ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 81.31% 32ms 0.77% PMPI_Init_thread 0 0% 81.31% 32ms 0.77% PetscInitialize 0 0% 81.31% 32ms 0.77% PetscSetup::setUpWorld 0 0% 81.31% 32ms 0.77% PetscSetupUtils::CommonSetup 0 0% 81.31% 32ms 0.77% PetscSetupUtils::InitialisePetsc 0 0% 81.31% 32ms 0.77% ompi_mpi_init 31ms 0.75% 82.06% 31ms 0.75% DistributedVector::Iterator::operator!= 0 0% 82.06% 31ms 0.75% PetscMemcpy (inline) 0 0% 82.06% 31ms 0.75% VecCreate_MPI_Private 1ms 0.024% 82.08% 30ms 0.72% PetscMallocAlign 13ms 0.31% 82.40% 30ms 0.72% TimeStepper::AdvanceOneTimeStep 9ms 0.22% 82.61% 30ms 0.72% std::__cxx11::basic_string::basic_string 3ms 0.072% 82.68% 29ms 0.7% 23ms 0.55% 83.24% 29ms 0.7% AbstractParameterisedSystem::GetAnyVariable 1ms 0.024% 83.26% 28ms 0.67% VecCopy_Seq (partial-inline) 28ms 0.67% 83.93% 28ms 0.67% [libchaste_heart.so] 28ms 0.67% 84.61% 28ms 0.67% asuint64 (inline) 28ms 0.67% 85.28% 28ms 0.67% std::vector::operator[] (inline) 24ms 0.58% 85.86% 27ms 0.65% AbstractParameterisedSystem::SetAnyVariable 27ms 0.65% 86.51% 27ms 0.65% DistributedVector::Stripe::operator[] (inline) 0 0% 86.51% 25ms 0.6% VecSetType 2ms 0.048% 86.56% 24ms 0.58% VecAXPY 0 0% 86.56% 24ms 0.58% VecNorm_MPI 0 0% 86.56% 24ms 0.58% VecNorm_MPI_Default.constprop.0 3ms 0.072% 86.63% 23ms 0.55% AbstractCardiacCell::GetVoltage (inline) 22ms 0.53% 87.16% 22ms 0.53% [libm.so.6] 0 0% 87.16% 21ms 0.51% orte_init