File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3601ms, 86.62% of 4157ms total Dropped 446 nodes (cum <= 20.79ms) flat flat% sum% cum cum% 0 0% 0% 4150ms 99.83% __libc_start_call_main 0 0% 0% 4150ms 99.83% __libc_start_main_impl 0 0% 0% 4150ms 99.83% _start 0 0% 0% 4147ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4147ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4147ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4147ms 99.76% main 0 0% 0% 4108ms 98.82% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4108ms 98.82% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4108ms 98.82% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4100ms 98.63% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4087ms 98.32% AbstractDynamicLinearPdeSolver::Solve 16ms 0.38% 0.41% 2399ms 57.71% AbstractCardiacTissue::SolveCellSystems 0 0% 0.41% 1381ms 33.22% LinearSystem::Solve 39ms 0.94% 1.35% 1360ms 32.72% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.35% 1345ms 32.36% KSPSolve 5ms 0.12% 1.47% 1345ms 32.36% KSPSolve_Private 0 0% 1.47% 1293ms 31.10% KSPSolve_SYMMLQ 28ms 0.67% 2.14% 1143ms 27.50% AbstractOneStepIvpOdeSolver::InternalSolve 60ms 1.44% 3.58% 978ms 23.53% EulerIvpOdeSolver::CalculateNextYValue 266ms 6.40% 9.98% 906ms 21.79% CellLuoRudy1991FromCellML::EvaluateYDerivatives 2ms 0.048% 10.03% 812ms 19.53% PCApply 360ms 8.66% 18.69% 807ms 19.41% __GI___exp 0 0% 18.69% 803ms 19.32% PCApply_BJacobi_Singleblock 33ms 0.79% 19.49% 796ms 19.15% AbstractCardiacTissue::UpdateCaches (inline) 255ms 6.13% 25.62% 734ms 17.66% CellLuoRudy1991FromCellML::GetIIonic 0 0% 25.62% 675ms 16.24% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 25.64% 635ms 15.28% MatSolve 0 0% 25.64% 635ms 15.28% PCApply_ICC 626ms 15.06% 40.70% 629ms 15.13% MatSolve_SeqSBAIJ_1_NaturalOrdering 1ms 0.024% 40.73% 597ms 14.36% KSP_PCApply.lto_priv.18 402ms 9.67% 50.40% 424ms 10.20% __ieee754_exp_fma 8ms 0.19% 50.59% 388ms 9.33% MatMult 3ms 0.072% 50.66% 373ms 8.97% MatMult_SeqAIJ 365ms 8.78% 59.44% 372ms 8.95% MatMult_SeqAIJ_Inode (inline) 1ms 0.024% 59.47% 286ms 6.88% KSPConvergedDefault 56ms 1.35% 60.81% 273ms 6.57% BidomainSolver::SetupLinearSystem 0 0% 60.81% 270ms 6.50% KSP_PCApply (partial-inline) 1ms 0.024% 60.84% 252ms 6.06% std::pow (inline) 27ms 0.65% 61.49% 251ms 6.04% __pow 26ms 0.63% 62.11% 224ms 5.39% __ieee754_pow_fma 84ms 2.02% 64.13% 173ms 4.16% CellLuoRudy1991FromCellML::VerifyStateVariables 117ms 2.81% 66.95% 117ms 2.81% log_inline (inline) 0 0% 66.95% 99ms 2.38% MatNullSpaceRemove 0 0% 66.95% 92ms 2.21% VecDuplicate 27ms 0.65% 67.60% 84ms 2.02% MPI_Wtime 74ms 1.78% 69.38% 74ms 1.78% exp_inline (inline) 70ms 1.68% 71.06% 70ms 1.68% ddot_ 43ms 1.03% 72.10% 67ms 1.61% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 44ms 1.06% 73.15% 63ms 1.52% TimeStepper::TimeStepper 0 0% 73.15% 59ms 1.42% VecMDot 6ms 0.14% 73.30% 59ms 1.42% VecMXDot_Private 1ms 0.024% 73.32% 57ms 1.37% __GI___clock_gettime 6ms 0.14% 73.47% 56ms 1.35% VecCopy 2ms 0.048% 73.51% 56ms 1.35% VecDuplicate_MPI 56ms 1.35% 74.86% 56ms 1.35% [[vdso]] 2ms 0.048% 74.91% 54ms 1.30% VecDot 11ms 0.26% 75.17% 53ms 1.27% PetscLogEventEndDefault 7ms 0.17% 75.34% 52ms 1.25% PetscLogEventBeginDefault 0 0% 75.34% 47ms 1.13% VecDot_Seq 0 0% 75.34% 47ms 1.13% VecXDot_Seq_Private.constprop.0 3ms 0.072% 75.41% 46ms 1.11% VecDestroy 21ms 0.51% 75.92% 44ms 1.06% AbstractCardiacCell::SetVoltage (partial-inline) 5ms 0.12% 76.04% 40ms 0.96% VecMAXPY 40ms 0.96% 77.00% 40ms 0.96% std::isinf (inline) 38ms 0.91% 77.92% 39ms 0.94% __ieee754_log_fma 6ms 0.14% 78.06% 38ms 0.91% VecCreate_MPI_Private (partial-inline) 0 0% 78.06% 38ms 0.91% VecNorm 32ms 0.77% 78.83% 37ms 0.89% AbstractOneStepIvpOdeSolver::Solve 37ms 0.89% 79.72% 37ms 0.89% VecMDot_Seq 31ms 0.75% 80.47% 36ms 0.87% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 80.47% 36ms 0.87% VecDuplicate_Seq 0 0% 80.47% 34ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 80.47% 34ms 0.82% KSP_RemoveNullSpace (inline) 0 0% 80.47% 34ms 0.82% PetscInitialize 0 0% 80.47% 34ms 0.82% PetscSetup::setUpWorld 0 0% 80.47% 34ms 0.82% PetscSetupUtils::CommonSetup 0 0% 80.47% 34ms 0.82% PetscSetupUtils::InitialisePetsc 0 0% 80.47% 34ms 0.82% VecCreate 0 0% 80.47% 33ms 0.79% PMPI_Init_thread 0 0% 80.47% 33ms 0.79% VecNorm_Seq 33ms 0.79% 81.26% 33ms 0.79% [libchaste_heart.so] 0 0% 81.26% 33ms 0.79% ompi_mpi_init 1ms 0.024% 81.28% 32ms 0.77% VecAXPY 32ms 0.77% 82.05% 32ms 0.77% __memcpy_avx_unaligned_erms 4ms 0.096% 82.15% 30ms 0.72% PetscMallocA 3ms 0.072% 82.22% 29ms 0.7% 0 0% 82.22% 29ms 0.7% PetscMemcpy (inline) 29ms 0.7% 82.92% 29ms 0.7% TimeStepper::CalculateNextTime 18ms 0.43% 83.35% 28ms 0.67% AbstractParameterisedSystem::GetAnyVariable 14ms 0.34% 83.69% 28ms 0.67% top12 (inline) 10ms 0.24% 83.93% 27ms 0.65% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.93% 27ms 0.65% AbstractBidomainSolver::FinaliseLinearSystem 13ms 0.31% 84.24% 27ms 0.65% TimeStepper::AdvanceOneTimeStep 1ms 0.024% 84.27% 26ms 0.63% PetscMallocAlign 13ms 0.31% 84.58% 26ms 0.63% std::__cxx11::basic_string::basic_string 5ms 0.12% 84.70% 25ms 0.6% PetscHeaderDestroy_Private 0 0% 84.70% 24ms 0.58% orte_init 1ms 0.024% 84.72% 23ms 0.55% VecStashCreate_Private 23ms 0.55% 85.28% 23ms 0.55% [libm.so.6] 9ms 0.22% 85.49% 22ms 0.53% AbstractCardiacCell::GetVoltage (partial-inline) 2ms 0.048% 85.54% 22ms 0.53% VecCopy_Seq (partial-inline) 22ms 0.53% 86.07% 22ms 0.53% VecMAXPY_Seq 22ms 0.53% 86.60% 22ms 0.53% std::vector::operator[] (inline) 0 0% 86.60% 21ms 0.51% AbstractFeVolumeIntegralAssembler::DoAssemble 0 0% 86.60% 21ms 0.51% VecNorm_MPI 1ms 0.024% 86.62% 21ms 0.51% VecNorm_MPI_Default.constprop.0