File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3705ms, 87.40% of 4239ms total Dropped 478 nodes (cum <= 21.20ms) flat flat% sum% cum cum% 0 0% 0% 4233ms 99.86% __libc_start_call_main 0 0% 0% 4233ms 99.86% __libc_start_main_impl 0 0% 0% 4233ms 99.86% _start 0 0% 0% 4231ms 99.81% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4231ms 99.81% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4231ms 99.81% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4231ms 99.81% main 0 0% 0% 4197ms 99.01% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4197ms 99.01% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4197ms 99.01% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.024% 0.024% 4189ms 98.82% AbstractCardiacProblem::Solve 2ms 0.047% 0.071% 4174ms 98.47% AbstractDynamicLinearPdeSolver::Solve 11ms 0.26% 0.33% 2125ms 50.13% AbstractCardiacTissue::SolveCellSystems 0 0% 0.33% 1734ms 40.91% LinearSystem::Solve 0 0% 0.33% 1703ms 40.17% KSPSolve 1ms 0.024% 0.35% 1703ms 40.17% KSPSolve_Private 3ms 0.071% 0.42% 1657ms 39.09% KSPSolve_SYMMLQ 35ms 0.83% 1.25% 1240ms 29.25% AbstractCardiacCell::ComputeExceptVoltage 3ms 0.071% 1.32% 1105ms 26.07% PCApply 0 0% 1.32% 1098ms 25.90% PCApply_BJacobi_Singleblock 27ms 0.64% 1.96% 1070ms 25.24% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.96% 943ms 22.25% KSPSolve_PREONLY (partial-inline) 46ms 1.09% 3.04% 916ms 21.61% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.024% 3.07% 892ms 21.04% PCApply_ICC 3ms 0.071% 3.14% 891ms 21.02% MatSolve 814ms 19.20% 22.34% 886ms 20.90% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 230ms 5.43% 27.77% 860ms 20.29% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 27.77% 834ms 19.67% KSP_PCApply.lto_priv.18 367ms 8.66% 36.42% 780ms 18.40% __GI___exp 22ms 0.52% 36.94% 689ms 16.25% AbstractCardiacTissue::UpdateCaches (inline) 230ms 5.43% 42.37% 638ms 15.05% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.047% 42.42% 397ms 9.37% MatMult 362ms 8.54% 50.96% 390ms 9.20% __ieee754_exp_fma 4ms 0.094% 51.05% 389ms 9.18% MatMult_SeqAIJ 380ms 8.96% 60.01% 388ms 9.15% MatMult_SeqAIJ_Inode (inline) 1ms 0.024% 60.04% 363ms 8.56% KSPConvergedDefault 0 0% 60.04% 344ms 8.12% KSP_PCApply (partial-inline) 44ms 1.04% 61.08% 265ms 6.25% BidomainSolver::SetupLinearSystem 2ms 0.047% 61.12% 197ms 4.65% std::pow (inline) 16ms 0.38% 61.50% 195ms 4.60% __pow 31ms 0.73% 62.23% 179ms 4.22% __ieee754_pow_fma 60ms 1.42% 63.65% 145ms 3.42% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 63.67% 129ms 3.04% MatNullSpaceRemove 114ms 2.69% 66.36% 114ms 2.69% __memcpy_avx_unaligned_erms 0 0% 66.36% 107ms 2.52% PetscMemcpy (inline) 1ms 0.024% 66.38% 99ms 2.34% VecDuplicate 82ms 1.93% 68.32% 82ms 1.93% log_inline (inline) 20ms 0.47% 68.79% 81ms 1.91% MPI_Wtime 81ms 1.91% 70.70% 81ms 1.91% ddot_ 1ms 0.024% 70.72% 79ms 1.86% VecMDot 3ms 0.071% 70.79% 78ms 1.84% VecMXDot_Private 5ms 0.12% 70.91% 70ms 1.65% VecCopy 19ms 0.45% 71.36% 67ms 1.58% PetscLogEventBeginDefault 63ms 1.49% 72.85% 63ms 1.49% exp_inline (inline) 17ms 0.4% 73.25% 62ms 1.46% PetscLogEventEndDefault 1ms 0.024% 73.27% 61ms 1.44% VecDot 1ms 0.024% 73.30% 61ms 1.44% __GI___clock_gettime 60ms 1.42% 74.71% 60ms 1.42% [[vdso]] 27ms 0.64% 75.35% 53ms 1.25% TimeStepper::TimeStepper 0 0% 75.35% 53ms 1.25% VecDot_Seq 0 0% 75.35% 53ms 1.25% VecXDot_Seq_Private.constprop.0 1ms 0.024% 75.37% 52ms 1.23% VecDuplicate_Seq 3ms 0.071% 75.44% 51ms 1.20% VecDestroy 1ms 0.024% 75.47% 49ms 1.16% VecMAXPY 2ms 0.047% 75.51% 46ms 1.09% VecDuplicate_MPI 3ms 0.071% 75.58% 44ms 1.04% AbstractBidomainSolver::FinaliseLinearSystem 44ms 1.04% 76.62% 44ms 1.04% VecMDot_Seq 44ms 1.04% 77.66% 44ms 1.04% std::isinf (inline) 6ms 0.14% 77.80% 43ms 1.01% PetscMallocA 2ms 0.047% 77.85% 42ms 0.99% VecNorm 15ms 0.35% 78.20% 41ms 0.97% AbstractBidomainSolver::CheckCompatibilityCondition 8ms 0.19% 78.39% 41ms 0.97% AbstractCardiacCell::SetVoltage (partial-inline) 16ms 0.38% 78.77% 41ms 0.97% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 3ms 0.071% 78.84% 41ms 0.97% VecCreate 40ms 0.94% 79.78% 40ms 0.94% __ieee754_log_fma 39ms 0.92% 80.70% 39ms 0.92% [libchaste_heart.so] 38ms 0.9% 81.60% 38ms 0.9% TimeStepper::CalculateNextTime 1ms 0.024% 81.62% 38ms 0.9% VecCopy_Seq (partial-inline) 32ms 0.75% 82.38% 37ms 0.87% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 5ms 0.12% 82.50% 37ms 0.87% PetscMallocAlign 1ms 0.024% 82.52% 37ms 0.87% VecAXPY 20ms 0.47% 82.99% 33ms 0.78% AbstractOneStepIvpOdeSolver::Solve 0 0% 82.99% 33ms 0.78% VecNorm_Seq 0 0% 82.99% 32ms 0.75% CxxTest::RealWorldDescription::setUp 0 0% 82.99% 32ms 0.75% PetscInitialize 0 0% 82.99% 32ms 0.75% PetscSetup::setUpWorld 0 0% 82.99% 32ms 0.75% PetscSetupUtils::CommonSetup 0 0% 82.99% 32ms 0.75% PetscSetupUtils::InitialisePetsc 2ms 0.047% 83.04% 32ms 0.75% VecSetType 0 0% 83.04% 31ms 0.73% PMPI_Init_thread 0 0% 83.04% 31ms 0.73% ompi_mpi_init 14ms 0.33% 83.37% 30ms 0.71% top12 (inline) 27ms 0.64% 84.01% 28ms 0.66% VecMAXPY_Seq 12ms 0.28% 84.29% 28ms 0.66% std::__cxx11::basic_string::basic_string 27ms 0.64% 84.93% 27ms 0.64% DistributedVector::Iterator::operator!= 24ms 0.57% 85.49% 26ms 0.61% AbstractParameterisedSystem::SetAnyVariable 1ms 0.024% 85.52% 25ms 0.59% 11ms 0.26% 85.77% 25ms 0.59% TimeStepper::AdvanceOneTimeStep 19ms 0.45% 86.22% 24ms 0.57% AbstractParameterisedSystem::GetAnyVariable 2ms 0.047% 86.27% 24ms 0.57% __GI___libc_malloc 0 0% 86.27% 24ms 0.57% orte_init 0 0% 86.27% 23ms 0.54% VecCreate_Seq 23ms 0.54% 86.81% 23ms 0.54% [libm.so.6] 22ms 0.52% 87.33% 22ms 0.52% DistributedVector::Stripe::operator[] (inline) 2ms 0.047% 87.38% 22ms 0.52% PetscHeaderDestroy_Private 1ms 0.024% 87.40% 22ms 0.52% VecAXPY_Seq