File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3302ms, 90.19% of 3661ms total Dropped 386 nodes (cum <= 18.30ms) flat flat% sum% cum cum% 0 0% 0% 3654ms 99.81% __libc_start_call_main 0 0% 0% 3654ms 99.81% __libc_start_main_impl 0 0% 0% 3654ms 99.81% _start 0 0% 0% 3651ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 3651ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 3651ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 3651ms 99.73% main 0 0% 0% 3615ms 98.74% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3615ms 98.74% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3615ms 98.74% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 3608ms 98.55% AbstractCardiacProblem::Solve 1ms 0.027% 0.027% 3598ms 98.28% AbstractDynamicLinearPdeSolver::Solve 15ms 0.41% 0.44% 1897ms 51.82% AbstractCardiacTissue::SolveCellSystems 0 0% 0.44% 1386ms 37.86% LinearSystem::Solve 0 0% 0.44% 1358ms 37.09% KSPSolve 3ms 0.082% 0.52% 1358ms 37.09% KSPSolve_Private 2ms 0.055% 0.57% 1328ms 36.27% KSPSolve_SYMMLQ 13ms 0.36% 0.93% 1081ms 29.53% AbstractCardiacCell::ComputeExceptVoltage 26ms 0.71% 1.64% 951ms 25.98% AbstractOneStepIvpOdeSolver::InternalSolve 88ms 2.40% 4.04% 816ms 22.29% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.027% 4.07% 762ms 20.81% PCApply 3ms 0.082% 4.15% 760ms 20.76% PCApply_BJacobi_Singleblock 264ms 7.21% 11.36% 722ms 19.72% CellLuoRudy1991FromCellML::EvaluateYDerivatives 25ms 0.68% 12.05% 657ms 17.95% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 12.05% 646ms 17.65% KSPSolve_PREONLY (partial-inline) 261ms 7.13% 19.18% 614ms 16.77% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.055% 19.23% 600ms 16.39% MatSolve 0 0% 19.23% 600ms 16.39% PCApply_ICC 592ms 16.17% 35.40% 592ms 16.17% MatSolve_SeqSBAIJ_1_NaturalOrdering 2ms 0.055% 35.45% 571ms 15.60% MatMult 9ms 0.25% 35.70% 567ms 15.49% MatMult_SeqAIJ (partial-inline) 558ms 15.24% 50.94% 562ms 15.35% MatMult_SeqAIJ_Inode (inline) 200ms 5.46% 56.41% 537ms 14.67% __GI___exp 0 0% 56.41% 526ms 14.37% KSP_PCApply.lto_priv.18 0 0% 56.41% 307ms 8.39% KSPConvergedDefault 0 0% 56.41% 300ms 8.19% KSP_PCApply (partial-inline) 294ms 8.03% 64.44% 295ms 8.06% __ieee754_exp_fma 32ms 0.87% 65.31% 287ms 7.84% BidomainSolver::SetupLinearSystem 9ms 0.25% 65.56% 186ms 5.08% std::pow (inline) 9ms 0.25% 65.80% 177ms 4.83% __pow 6ms 0.16% 65.97% 164ms 4.48% __ieee754_pow_fma 65ms 1.78% 67.74% 112ms 3.06% CellLuoRudy1991FromCellML::VerifyStateVariables 112ms 3.06% 70.80% 112ms 3.06% [libchaste_heart.so] 2ms 0.055% 70.85% 110ms 3.00% MatNullSpaceRemove 81ms 2.21% 73.07% 81ms 2.21% log_inline (inline) 71ms 1.94% 75.01% 77ms 2.10% exp_inline (inline) 17ms 0.46% 75.47% 70ms 1.91% MPI_Wtime 0 0% 75.47% 70ms 1.91% VecDuplicate 0 0% 75.47% 69ms 1.88% VecMDot 5ms 0.14% 75.61% 69ms 1.88% VecMXDot_Private 52ms 1.42% 77.03% 52ms 1.42% [[vdso]] 52ms 1.42% 78.45% 52ms 1.42% [libm.so.6] 0 0% 78.45% 52ms 1.42% __GI___clock_gettime 23ms 0.63% 79.08% 50ms 1.37% TimeStepper::TimeStepper 47ms 1.28% 80.36% 47ms 1.28% ddot_ 5ms 0.14% 80.50% 43ms 1.17% PetscLogEventBeginDefault 1ms 0.027% 80.52% 43ms 1.17% VecDestroy 40ms 1.09% 81.62% 40ms 1.09% TimeStepper::CalculateNextTime 4ms 0.11% 81.73% 40ms 1.09% VecCopy 5ms 0.14% 81.86% 39ms 1.07% VecMAXPY 5ms 0.14% 82.00% 38ms 1.04% PetscLogEventEndDefault 35ms 0.96% 82.96% 38ms 1.04% VecMDot_Seq 23ms 0.63% 83.58% 37ms 1.01% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.027% 83.61% 36ms 0.98% VecAXPY 1ms 0.027% 83.64% 36ms 0.98% VecDuplicate_Seq 0 0% 83.64% 36ms 0.98% VecNorm 0 0% 83.64% 34ms 0.93% VecDuplicate_MPI 27ms 0.74% 84.38% 33ms 0.9% AbstractOneStepIvpOdeSolver::Solve 0 0% 84.38% 33ms 0.9% CxxTest::RealWorldDescription::setUp 0 0% 84.38% 33ms 0.9% PMPI_Init_thread 0 0% 84.38% 33ms 0.9% PetscInitialize 0 0% 84.38% 33ms 0.9% PetscSetup::setUpWorld 0 0% 84.38% 33ms 0.9% PetscSetupUtils::CommonSetup 0 0% 84.38% 33ms 0.9% PetscSetupUtils::InitialisePetsc 0 0% 84.38% 33ms 0.9% ompi_mpi_init 2ms 0.055% 84.43% 32ms 0.87% VecNorm_Seq 32ms 0.87% 85.30% 32ms 0.87% __ieee754_log_fma 0 0% 85.30% 31ms 0.85% VecCreate 30ms 0.82% 86.12% 30ms 0.82% [libchaste_ode.so] 1ms 0.027% 86.15% 29ms 0.79% 0 0% 86.15% 29ms 0.79% VecDot 1ms 0.027% 86.18% 28ms 0.76% PetscMallocA 13ms 0.36% 86.53% 28ms 0.76% TimeStepper::AdvanceOneTimeStep 1ms 0.027% 86.56% 27ms 0.74% PetscMallocAlign 0 0% 86.56% 27ms 0.74% VecDot_Seq 1ms 0.027% 86.59% 27ms 0.74% VecXDot_Seq_Private.constprop.0 6ms 0.16% 86.75% 26ms 0.71% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 86.75% 24ms 0.66% VecAXPY_Seq 23ms 0.63% 87.38% 24ms 0.66% VecMAXPY_Seq 24ms 0.66% 88.04% 24ms 0.66% daxpy_ 6ms 0.16% 88.20% 23ms 0.63% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 88.20% 23ms 0.63% AbstractBidomainSolver::FinaliseLinearSystem 9ms 0.25% 88.45% 22ms 0.6% AbstractCardiacCell::GetVoltage 6ms 0.16% 88.61% 22ms 0.6% __GI___libc_free 10ms 0.27% 88.88% 22ms 0.6% std::__cxx11::basic_string::basic_string 6ms 0.16% 89.05% 21ms 0.57% PetscFreeAlign 0 0% 89.05% 21ms 0.57% VecCreate_MPI_Private 0 0% 89.05% 21ms 0.57% VecNorm_MPI 0 0% 89.05% 21ms 0.57% VecNorm_MPI_Default.constprop.0 1ms 0.027% 89.07% 20ms 0.55% PMPI_Comm_get_attr 20ms 0.55% 89.62% 20ms 0.55% __memcpy_avx512_unaligned_erms 6ms 0.16% 89.78% 20ms 0.55% __posix_memalign 0 0% 89.78% 20ms 0.55% orte_init 11ms 0.3% 90.08% 19ms 0.52% __GI___libc_malloc 4ms 0.11% 90.19% 19ms 0.52% ompi_attr_get_c