File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3654ms, 85.92% of 4253ms total Dropped 496 nodes (cum <= 21.27ms) flat flat% sum% cum cum% 0 0% 0% 4244ms 99.79% __libc_start_call_main 0 0% 0% 4244ms 99.79% __libc_start_main_impl 0 0% 0% 4244ms 99.79% _start 0 0% 0% 4241ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4241ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4241ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4241ms 99.72% main 0 0% 0% 4204ms 98.85% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4204ms 98.85% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4204ms 98.85% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4196ms 98.66% AbstractCardiacProblem::Solve 2ms 0.047% 0.047% 4181ms 98.31% AbstractDynamicLinearPdeSolver::Solve 14ms 0.33% 0.38% 2132ms 50.13% AbstractCardiacTissue::SolveCellSystems 0 0% 0.38% 1746ms 41.05% LinearSystem::Solve 0 0% 0.38% 1685ms 39.62% KSPSolve 12ms 0.28% 0.66% 1685ms 39.62% KSPSolve_Private 3ms 0.071% 0.73% 1642ms 38.61% KSPSolve_SYMMLQ 36ms 0.85% 1.58% 1257ms 29.56% AbstractCardiacCell::ComputeExceptVoltage 4ms 0.094% 1.67% 1172ms 27.56% PCApply 1ms 0.024% 1.69% 1164ms 27.37% PCApply_BJacobi_Singleblock 21ms 0.49% 2.19% 1051ms 24.71% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 2.19% 984ms 23.14% KSPSolve_PREONLY (partial-inline) 2ms 0.047% 2.23% 937ms 22.03% PCApply_ICC 5ms 0.12% 2.35% 935ms 21.98% MatSolve 849ms 19.96% 22.31% 924ms 21.73% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 50ms 1.18% 23.49% 898ms 21.11% EulerIvpOdeSolver::CalculateNextYValue 0 0% 23.49% 870ms 20.46% KSP_PCApply.lto_priv.18 236ms 5.55% 29.04% 826ms 19.42% CellLuoRudy1991FromCellML::EvaluateYDerivatives 307ms 7.22% 36.26% 725ms 17.05% __GI___exp 27ms 0.63% 36.89% 688ms 16.18% AbstractCardiacTissue::UpdateCaches (inline) 218ms 5.13% 42.02% 631ms 14.84% CellLuoRudy1991FromCellML::GetIIonic 374ms 8.79% 50.81% 397ms 9.33% __ieee754_exp_fma 4ms 0.094% 50.91% 377ms 8.86% MatMult 0 0% 50.91% 367ms 8.63% KSPConvergedDefault 2ms 0.047% 50.95% 365ms 8.58% MatMult_SeqAIJ 361ms 8.49% 59.44% 365ms 8.58% MatMult_SeqAIJ_Inode (inline) 0 0% 59.44% 356ms 8.37% KSP_PCApply (partial-inline) 47ms 1.11% 60.55% 262ms 6.16% BidomainSolver::SetupLinearSystem 1ms 0.024% 60.57% 220ms 5.17% std::pow (inline) 17ms 0.4% 60.97% 219ms 5.15% __pow 24ms 0.56% 61.53% 202ms 4.75% __ieee754_pow_fma 70ms 1.65% 63.18% 137ms 3.22% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 63.18% 118ms 2.77% VecDuplicate 0 0% 63.18% 115ms 2.70% MatNullSpaceRemove 104ms 2.45% 65.62% 104ms 2.45% __memcpy_avx_unaligned_erms 0 0% 65.62% 102ms 2.40% PetscMemcpy (inline) 91ms 2.14% 67.76% 91ms 2.14% log_inline (inline) 23ms 0.54% 68.30% 87ms 2.05% MPI_Wtime 77ms 1.81% 70.12% 78ms 1.83% exp_inline (inline) 1ms 0.024% 70.14% 75ms 1.76% VecMDot 4ms 0.094% 70.23% 74ms 1.74% VecMXDot_Private 12ms 0.28% 70.51% 65ms 1.53% PetscLogEventBeginDefault 8ms 0.19% 70.70% 64ms 1.50% PetscLogEventEndDefault 5ms 0.12% 70.82% 64ms 1.50% VecCopy 64ms 1.50% 72.33% 64ms 1.50% [[vdso]] 0 0% 72.33% 64ms 1.50% __GI___clock_gettime 26ms 0.61% 72.94% 63ms 1.48% TimeStepper::TimeStepper 61ms 1.43% 74.37% 61ms 1.43% ddot_ 2ms 0.047% 74.42% 60ms 1.41% VecDuplicate_MPI 0 0% 74.42% 58ms 1.36% VecDuplicate_Seq 7ms 0.16% 74.58% 55ms 1.29% PetscMallocA 46ms 1.08% 75.66% 50ms 1.18% VecMDot_Seq 2ms 0.047% 75.71% 48ms 1.13% PetscMallocAlign (partial-inline) 40ms 0.94% 76.65% 47ms 1.11% AbstractOneStepIvpOdeSolver::Solve 0 0% 76.65% 46ms 1.08% VecCreate 1ms 0.024% 76.68% 46ms 1.08% VecDestroy 1ms 0.024% 76.70% 46ms 1.08% VecNorm 2ms 0.047% 76.75% 45ms 1.06% VecDot 12ms 0.28% 77.03% 42ms 0.99% AbstractCardiacCell::SetVoltage (partial-inline) 27ms 0.63% 77.66% 42ms 0.99% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 39ms 0.92% 78.58% 42ms 0.99% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.024% 78.60% 40ms 0.94% VecMAXPY 0 0% 78.60% 39ms 0.92% VecDot_Seq 1ms 0.024% 78.63% 39ms 0.92% VecXDot_Seq_Private.constprop.0 1ms 0.024% 78.65% 38ms 0.89% VecAXPY 0 0% 78.65% 37ms 0.87% PetscMemzero (inline) 37ms 0.87% 79.52% 37ms 0.87% TimeStepper::CalculateNextTime 0 0% 79.52% 37ms 0.87% VecNorm_Seq 34ms 0.8% 80.32% 36ms 0.85% __ieee754_log_fma 35ms 0.82% 81.14% 35ms 0.82% __memset_avx2_unaligned_erms 1ms 0.024% 81.17% 34ms 0.8% VecCreate_MPI_Private 0 0% 81.17% 33ms 0.78% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.071% 81.24% 33ms 0.78% VecSetType 10ms 0.24% 81.47% 32ms 0.75% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.47% 32ms 0.75% CxxTest::RealWorldDescription::setUp 0 0% 81.47% 32ms 0.75% PMPI_Init_thread 0 0% 81.47% 32ms 0.75% PetscInitialize 0 0% 81.47% 32ms 0.75% PetscSetup::setUpWorld 0 0% 81.47% 32ms 0.75% PetscSetupUtils::CommonSetup 0 0% 81.47% 32ms 0.75% PetscSetupUtils::InitialisePetsc 32ms 0.75% 82.22% 32ms 0.75% [libchaste_heart.so] 0 0% 82.22% 32ms 0.75% ompi_mpi_init 15ms 0.35% 82.58% 28ms 0.66% top12 (inline) 1ms 0.024% 82.60% 27ms 0.63% 13ms 0.31% 82.91% 27ms 0.63% std::__cxx11::basic_string::basic_string 20ms 0.47% 83.38% 26ms 0.61% AbstractParameterisedSystem::GetAnyVariable 0 0% 83.38% 26ms 0.61% VecCopy_Seq (partial-inline) 4ms 0.094% 83.47% 26ms 0.61% VecSet 26ms 0.61% 84.08% 26ms 0.61% std::isinf (inline) 23ms 0.54% 84.62% 25ms 0.59% AbstractParameterisedSystem::SetAnyVariable 4ms 0.094% 84.72% 25ms 0.59% PetscHeaderCreate_Private 0 0% 84.72% 25ms 0.59% VecCreate_Seq 24ms 0.56% 85.28% 25ms 0.59% VecMAXPY_Seq 0 0% 85.28% 24ms 0.56% VecAXPY_Seq 0 0% 85.28% 24ms 0.56% VecNorm_MPI 0 0% 85.28% 24ms 0.56% VecNorm_MPI_Default.constprop.0 15ms 0.35% 85.63% 22ms 0.52% TimeStepper::AdvanceOneTimeStep 10ms 0.24% 85.87% 22ms 0.52% __GI___libc_malloc 2ms 0.047% 85.92% 22ms 0.52% __posix_memalign