File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3561ms, 88.25% of 4035ms total Dropped 427 nodes (cum <= 20.18ms) flat flat% sum% cum cum% 0 0% 0% 4026ms 99.78% __libc_start_call_main 0 0% 0% 4026ms 99.78% __libc_start_main_impl 0 0% 0% 4026ms 99.78% _start 0 0% 0% 4023ms 99.70% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4023ms 99.70% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4023ms 99.70% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4023ms 99.70% main 0 0% 0% 3989ms 98.86% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3989ms 98.86% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3989ms 98.86% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 2ms 0.05% 0.05% 3982ms 98.69% AbstractCardiacProblem::Solve 1ms 0.025% 0.074% 3965ms 98.27% AbstractDynamicLinearPdeSolver::Solve 23ms 0.57% 0.64% 1991ms 49.34% AbstractCardiacTissue::SolveCellSystems 0 0% 0.64% 1539ms 38.14% LinearSystem::Solve 0 0% 0.64% 1506ms 37.32% KSPSolve 5ms 0.12% 0.77% 1506ms 37.32% KSPSolve_Private 0 0% 0.77% 1479ms 36.65% KSPSolve_SYMMLQ 18ms 0.45% 1.21% 1115ms 27.63% AbstractCardiacCell::ComputeExceptVoltage 24ms 0.59% 1.81% 990ms 24.54% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.81% 842ms 20.87% PCApply 1ms 0.025% 1.83% 836ms 20.72% PCApply_BJacobi_Singleblock 73ms 1.81% 3.64% 829ms 20.55% EulerIvpOdeSolver::CalculateNextYValue 263ms 6.52% 10.16% 745ms 18.46% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 10.16% 736ms 18.24% KSPSolve_PREONLY (partial-inline) 3ms 0.074% 10.24% 710ms 17.60% MatMult 15ms 0.37% 10.61% 703ms 17.42% AbstractCardiacTissue::UpdateCaches (inline) 6ms 0.15% 10.76% 702ms 17.40% MatMult_SeqAIJ (partial-inline) 694ms 17.20% 27.96% 702ms 17.40% MatMult_SeqAIJ_Inode (inline) 1ms 0.025% 27.98% 689ms 17.08% MatSolve 0 0% 27.98% 689ms 17.08% PCApply_ICC 677ms 16.78% 44.76% 684ms 16.95% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 303ms 7.51% 52.27% 669ms 16.58% CellLuoRudy1991FromCellML::GetIIonic 0 0% 52.27% 606ms 15.02% KSP_PCApply.lto_priv.18 202ms 5.01% 57.27% 562ms 13.93% __GI___exp 40ms 0.99% 58.27% 400ms 9.91% BidomainSolver::SetupLinearSystem 322ms 7.98% 66.25% 323ms 8.00% __ieee754_exp_fma 0 0% 66.25% 314ms 7.78% KSPConvergedDefault 0 0% 66.25% 304ms 7.53% KSP_PCApply (partial-inline) 2ms 0.05% 66.29% 198ms 4.91% std::pow (inline) 6ms 0.15% 66.44% 193ms 4.78% __pow 3ms 0.074% 66.52% 186ms 4.61% __ieee754_pow_fma 128ms 3.17% 69.69% 128ms 3.17% [libchaste_heart.so] 79ms 1.96% 71.65% 125ms 3.10% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 71.65% 109ms 2.70% MatNullSpaceRemove 89ms 2.21% 73.85% 102ms 2.53% exp_inline (inline) 39ms 0.97% 74.82% 78ms 1.93% TimeStepper::TimeStepper 78ms 1.93% 76.75% 78ms 1.93% log_inline (inline) 19ms 0.47% 77.22% 66ms 1.64% MPI_Wtime 0 0% 77.22% 65ms 1.61% VecDuplicate 0 0% 77.22% 61ms 1.51% VecMDot 7ms 0.17% 77.40% 61ms 1.51% VecMXDot_Private 8ms 0.2% 77.60% 53ms 1.31% PetscLogEventBeginDefault 51ms 1.26% 78.86% 51ms 1.26% ddot_ 7ms 0.17% 79.03% 48ms 1.19% VecMAXPY 46ms 1.14% 80.17% 46ms 1.14% [[vdso]] 0 0% 80.17% 46ms 1.14% __GI___clock_gettime 45ms 1.12% 81.29% 45ms 1.12% TimeStepper::CalculateNextTime 4ms 0.099% 81.39% 42ms 1.04% PetscLogEventEndDefault 4ms 0.099% 81.49% 42ms 1.04% VecDuplicate_MPI 4ms 0.099% 81.59% 41ms 1.02% VecAXPY 41ms 1.02% 82.60% 41ms 1.02% VecMDot_Seq 41ms 1.02% 83.62% 41ms 1.02% [libm.so.6] 5ms 0.12% 83.74% 40ms 0.99% VecCopy 0 0% 83.74% 38ms 0.94% KSP_RemoveNullSpace (inline) 2ms 0.05% 83.79% 37ms 0.92% VecDot 0 0% 83.79% 35ms 0.87% VecNorm 2ms 0.05% 83.84% 34ms 0.84% VecDestroy 10ms 0.25% 84.09% 32ms 0.79% AbstractCardiacCell::SetVoltage (partial-inline) 19ms 0.47% 84.56% 31ms 0.77% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 84.56% 31ms 0.77% VecDot_Seq 0 0% 84.56% 31ms 0.77% VecXDot_Seq_Private.constprop.0 0 0% 84.56% 30ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 84.56% 30ms 0.74% PetscInitialize 0 0% 84.56% 30ms 0.74% PetscSetup::setUpWorld 0 0% 84.56% 30ms 0.74% PetscSetupUtils::CommonSetup 0 0% 84.56% 30ms 0.74% PetscSetupUtils::InitialisePetsc 29ms 0.72% 85.28% 30ms 0.74% VecMAXPY_Seq 30ms 0.74% 86.02% 30ms 0.74% [libchaste_ode.so] 0 0% 86.02% 29ms 0.72% PMPI_Init_thread 0 0% 86.02% 29ms 0.72% VecNorm_Seq 0 0% 86.02% 29ms 0.72% ompi_mpi_init 4ms 0.099% 86.12% 28ms 0.69% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 86.12% 28ms 0.69% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 86.12% 27ms 0.67% 22ms 0.55% 86.67% 27ms 0.67% AbstractOneStepIvpOdeSolver::Solve 1ms 0.025% 86.69% 27ms 0.67% VecAXPY_Seq 2ms 0.05% 86.74% 26ms 0.64% VecCreate_MPI_Private 26ms 0.64% 87.39% 26ms 0.64% daxpy_ 10ms 0.25% 87.63% 25ms 0.62% TimeStepper::AdvanceOneTimeStep 1ms 0.025% 87.66% 25ms 0.62% VecNorm_MPI 0 0% 87.66% 24ms 0.59% VecNorm_MPI_Default.constprop.0 24ms 0.59% 88.25% 24ms 0.59% __memcpy_avx512_unaligned_erms 0 0% 88.25% 23ms 0.57% PetscMemcpy (inline) 0 0% 88.25% 23ms 0.57% VecDuplicate_Seq 0 0% 88.25% 21ms 0.52% VecCreate