File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3837ms, 88.55% of 4333ms total Dropped 428 nodes (cum <= 21.66ms) flat flat% sum% cum cum% 0 0% 0% 4324ms 99.79% __libc_start_call_main 0 0% 0% 4324ms 99.79% __libc_start_main_impl 0 0% 0% 4324ms 99.79% _start 0 0% 0% 4321ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4321ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4321ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4321ms 99.72% main 0 0% 0% 4286ms 98.92% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4286ms 98.92% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4286ms 98.92% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4279ms 98.75% AbstractCardiacProblem::Solve 0 0% 0% 4271ms 98.57% AbstractDynamicLinearPdeSolver::Solve 19ms 0.44% 0.44% 2066ms 47.68% AbstractCardiacTissue::SolveCellSystems 0 0% 0.44% 1710ms 39.46% LinearSystem::Solve 2ms 0.046% 0.48% 1667ms 38.47% KSPSolve 5ms 0.12% 0.6% 1667ms 38.47% KSPSolve_Private 0 0% 0.6% 1624ms 37.48% KSPSolve_SYMMLQ 10ms 0.23% 0.83% 1162ms 26.82% AbstractCardiacCell::ComputeExceptVoltage 20ms 0.46% 1.29% 1054ms 24.32% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.046% 1.34% 911ms 21.02% PCApply 1ms 0.023% 1.36% 904ms 20.86% PCApply_BJacobi_Singleblock 89ms 2.05% 3.42% 871ms 20.10% EulerIvpOdeSolver::CalculateNextYValue 4ms 0.092% 3.51% 839ms 19.36% MatMult 2ms 0.046% 3.55% 828ms 19.11% MatMult_SeqAIJ 821ms 18.95% 22.50% 828ms 19.11% MatMult_SeqAIJ_Inode (inline) 0 0% 22.50% 777ms 17.93% KSPSolve_PREONLY (partial-inline) 282ms 6.51% 29.01% 774ms 17.86% CellLuoRudy1991FromCellML::EvaluateYDerivatives 7ms 0.16% 29.17% 741ms 17.10% MatSolve 0 0% 29.17% 741ms 17.10% PCApply_ICC 725ms 16.73% 45.90% 729ms 16.82% MatSolve_SeqSBAIJ_1_NaturalOrdering 23ms 0.53% 46.43% 722ms 16.66% AbstractCardiacTissue::UpdateCaches (inline) 309ms 7.13% 53.57% 683ms 15.76% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.023% 53.59% 646ms 14.91% KSP_PCApply.lto_priv.18 202ms 4.66% 58.25% 585ms 13.50% __GI___exp 48ms 1.11% 59.36% 448ms 10.34% BidomainSolver::SetupLinearSystem 0 0% 59.36% 340ms 7.85% KSPConvergedDefault 331ms 7.64% 67.00% 334ms 7.71% __ieee754_exp_fma 0 0% 67.00% 322ms 7.43% KSP_PCApply (partial-inline) 4ms 0.092% 67.09% 193ms 4.45% std::pow (inline) 16ms 0.37% 67.46% 187ms 4.32% __pow 5ms 0.12% 67.57% 170ms 3.92% __ieee754_pow_fma 83ms 1.92% 69.49% 132ms 3.05% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.046% 69.54% 125ms 2.88% MatNullSpaceRemove 118ms 2.72% 72.26% 118ms 2.72% [libchaste_heart.so] 80ms 1.85% 74.11% 90ms 2.08% exp_inline (inline) 32ms 0.74% 74.84% 86ms 1.98% TimeStepper::TimeStepper 0 0% 74.84% 81ms 1.87% VecDuplicate 75ms 1.73% 76.58% 75ms 1.73% log_inline (inline) 0 0% 76.58% 69ms 1.59% VecMDot 5ms 0.12% 76.69% 69ms 1.59% VecMXDot_Private 16ms 0.37% 77.06% 67ms 1.55% MPI_Wtime 64ms 1.48% 78.54% 64ms 1.48% TimeStepper::CalculateNextTime 5ms 0.12% 78.65% 54ms 1.25% VecMAXPY 3ms 0.069% 78.72% 53ms 1.22% VecDuplicate_MPI 53ms 1.22% 79.94% 53ms 1.22% [libm.so.6] 52ms 1.20% 81.14% 52ms 1.20% ddot_ 9ms 0.21% 81.35% 50ms 1.15% PetscLogEventEndDefault 47ms 1.08% 82.44% 50ms 1.15% VecMDot_Seq 50ms 1.15% 83.59% 50ms 1.15% [[vdso]] 0 0% 83.59% 50ms 1.15% __GI___clock_gettime 2ms 0.046% 83.64% 47ms 1.08% VecDot 7ms 0.16% 83.80% 45ms 1.04% PetscLogEventBeginDefault 1ms 0.023% 83.82% 41ms 0.95% VecDestroy 4ms 0.092% 83.91% 40ms 0.92% VecCopy 9ms 0.21% 84.12% 39ms 0.9% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.12% 39ms 0.9% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.023% 84.14% 38ms 0.88% VecCreate 37ms 0.85% 85.00% 37ms 0.85% [libchaste_ode.so] 0 0% 85.00% 35ms 0.81% VecDot_Seq 34ms 0.78% 85.78% 35ms 0.81% VecMAXPY_Seq 0 0% 85.78% 35ms 0.81% VecNorm 0 0% 85.78% 35ms 0.81% VecXDot_Seq_Private.constprop.0 21ms 0.48% 86.27% 32ms 0.74% AbstractOneStepIvpOdeSolver::Solve 0 0% 86.27% 32ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 86.27% 32ms 0.74% PetscInitialize 6ms 0.14% 86.41% 32ms 0.74% PetscMallocA 0 0% 86.41% 32ms 0.74% PetscSetup::setUpWorld 0 0% 86.41% 32ms 0.74% PetscSetupUtils::CommonSetup 0 0% 86.41% 32ms 0.74% PetscSetupUtils::InitialisePetsc 0 0% 86.41% 31ms 0.72% PMPI_Init_thread 0 0% 86.41% 31ms 0.72% ompi_mpi_init 0 0% 86.41% 30ms 0.69% 0 0% 86.41% 30ms 0.69% VecNorm_Seq 30ms 0.69% 87.10% 30ms 0.69% __ieee754_log_fma 29ms 0.67% 87.77% 29ms 0.67% __memcpy_avx512_unaligned_erms 0 0% 87.77% 28ms 0.65% VecDuplicate_Seq 5ms 0.12% 87.88% 27ms 0.62% TimeStepper::AdvanceOneTimeStep 1ms 0.023% 87.91% 27ms 0.62% VecCreate_MPI_Private 1ms 0.023% 87.93% 26ms 0.6% PetscMallocAlign 6ms 0.14% 88.07% 25ms 0.58% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 10ms 0.23% 88.30% 25ms 0.58% __GI___libc_malloc 7ms 0.16% 88.46% 24ms 0.55% AbstractCardiacCell::SetVoltage (partial-inline) 3ms 0.069% 88.53% 24ms 0.55% PMPI_Comm_get_attr 0 0% 88.53% 23ms 0.53% PetscMemcpy (inline) 1ms 0.023% 88.55% 23ms 0.53% __posix_memalign 0 0% 88.55% 22ms 0.51% orte_init