File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3581ms, 89.01% of 4023ms total Dropped 414 nodes (cum <= 20.11ms) flat flat% sum% cum cum% 0 0% 0% 4016ms 99.83% __libc_start_call_main 0 0% 0% 4016ms 99.83% __libc_start_main_impl 0 0% 0% 4016ms 99.83% _start 0 0% 0% 4013ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4013ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4013ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4013ms 99.75% main 0 0% 0% 3983ms 99.01% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3983ms 99.01% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3983ms 99.01% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.025% 0.025% 3976ms 98.83% AbstractCardiacProblem::Solve 0 0% 0.025% 3966ms 98.58% AbstractDynamicLinearPdeSolver::Solve 13ms 0.32% 0.35% 2025ms 50.34% AbstractCardiacTissue::SolveCellSystems 1ms 0.025% 0.37% 1545ms 38.40% LinearSystem::Solve 0 0% 0.37% 1513ms 37.61% KSPSolve 1ms 0.025% 0.4% 1513ms 37.61% KSPSolve_Private 2ms 0.05% 0.45% 1486ms 36.94% KSPSolve_SYMMLQ 11ms 0.27% 0.72% 1189ms 29.56% AbstractCardiacCell::ComputeExceptVoltage 11ms 0.27% 0.99% 1076ms 26.75% AbstractOneStepIvpOdeSolver::InternalSolve 106ms 2.63% 3.63% 932ms 23.17% EulerIvpOdeSolver::CalculateNextYValue 3ms 0.075% 3.70% 877ms 21.80% PCApply 0 0% 3.70% 874ms 21.73% PCApply_BJacobi_Singleblock 287ms 7.13% 10.84% 819ms 20.36% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 10.84% 749ms 18.62% KSPSolve_PREONLY (partial-inline) 2ms 0.05% 10.89% 700ms 17.40% MatSolve 0 0% 10.89% 700ms 17.40% PCApply_ICC 688ms 17.10% 27.99% 690ms 17.15% MatSolve_SeqSBAIJ_1_NaturalOrdering 1ms 0.025% 28.01% 662ms 16.46% MatMult 3ms 0.075% 28.09% 654ms 16.26% MatMult_SeqAIJ 649ms 16.13% 44.22% 654ms 16.26% MatMult_SeqAIJ_Inode (inline) 9ms 0.22% 44.44% 652ms 16.21% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 44.44% 633ms 15.73% KSP_PCApply.lto_priv.18 278ms 6.91% 51.35% 615ms 15.29% CellLuoRudy1991FromCellML::GetIIonic 168ms 4.18% 55.53% 577ms 14.34% __GI___exp 358ms 8.90% 64.43% 360ms 8.95% __ieee754_exp_fma 31ms 0.77% 65.20% 359ms 8.92% BidomainSolver::SetupLinearSystem 1ms 0.025% 65.22% 312ms 7.76% KSPConvergedDefault 0 0% 65.22% 299ms 7.43% KSP_PCApply (partial-inline) 2ms 0.05% 65.27% 196ms 4.87% std::pow (inline) 6ms 0.15% 65.42% 192ms 4.77% __pow 12ms 0.3% 65.72% 181ms 4.50% __ieee754_pow_fma 90ms 2.24% 67.96% 141ms 3.50% CellLuoRudy1991FromCellML::VerifyStateVariables 126ms 3.13% 71.09% 126ms 3.13% [libchaste_heart.so] 0 0% 71.09% 109ms 2.71% MatNullSpaceRemove 79ms 1.96% 73.05% 90ms 2.24% exp_inline (inline) 25ms 0.62% 73.68% 86ms 2.14% MPI_Wtime 79ms 1.96% 75.64% 79ms 1.96% log_inline (inline) 0 0% 75.64% 66ms 1.64% VecMDot 3ms 0.075% 75.71% 66ms 1.64% VecMXDot_Private 21ms 0.52% 76.24% 61ms 1.52% TimeStepper::TimeStepper 59ms 1.47% 77.70% 59ms 1.47% [[vdso]] 59ms 1.47% 79.17% 59ms 1.47% [libm.so.6] 0 0% 79.17% 59ms 1.47% __GI___clock_gettime 9ms 0.22% 79.39% 58ms 1.44% PetscLogEventEndDefault 58ms 1.44% 80.84% 58ms 1.44% ddot_ 0 0% 80.84% 55ms 1.37% VecDuplicate 8ms 0.2% 81.03% 54ms 1.34% PetscLogEventBeginDefault 50ms 1.24% 82.28% 50ms 1.24% TimeStepper::CalculateNextTime 46ms 1.14% 83.42% 49ms 1.22% VecMDot_Seq 4ms 0.099% 83.52% 47ms 1.17% VecCopy 0 0% 83.52% 44ms 1.09% VecDot 2ms 0.05% 83.57% 43ms 1.07% VecMAXPY 1ms 0.025% 83.59% 42ms 1.04% VecDestroy 0 0% 83.59% 37ms 0.92% VecDot_Seq 1ms 0.025% 83.62% 37ms 0.92% VecNorm 0 0% 83.62% 37ms 0.92% VecXDot_Seq_Private.constprop.0 35ms 0.87% 84.49% 35ms 0.87% [libchaste_ode.so] 3ms 0.075% 84.56% 33ms 0.82% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 84.56% 33ms 0.82% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.075% 84.64% 33ms 0.82% VecAXPY 2ms 0.05% 84.69% 33ms 0.82% VecDuplicate_MPI 13ms 0.32% 85.01% 33ms 0.82% std::__cxx11::basic_string::basic_string 2ms 0.05% 85.06% 32ms 0.8% VecNorm_Seq 31ms 0.77% 85.83% 31ms 0.77% __ieee754_log_fma 6ms 0.15% 85.98% 30ms 0.75% PetscMallocA 28ms 0.7% 86.68% 29ms 0.72% VecMAXPY_Seq 14ms 0.35% 87.02% 28ms 0.7% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 10ms 0.25% 87.27% 27ms 0.67% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 87.27% 27ms 0.67% CxxTest::RealWorldDescription::setUp 0 0% 87.27% 27ms 0.67% PetscSetup::setUpWorld 0 0% 87.27% 27ms 0.67% PetscSetupUtils::CommonSetup 10ms 0.25% 87.52% 27ms 0.67% TimeStepper::AdvanceOneTimeStep 27ms 0.67% 88.19% 27ms 0.67% __memcpy_avx512_unaligned_erms 0 0% 88.19% 26ms 0.65% PMPI_Init_thread 0 0% 88.19% 26ms 0.65% PetscInitialize 0 0% 88.19% 26ms 0.65% PetscSetupUtils::InitialisePetsc 0 0% 88.19% 26ms 0.65% VecNorm_MPI 0 0% 88.19% 26ms 0.65% VecNorm_MPI_Default.constprop.0 0 0% 88.19% 26ms 0.65% ompi_mpi_init 18ms 0.45% 88.64% 24ms 0.6% AbstractOneStepIvpOdeSolver::Solve 1ms 0.025% 88.67% 24ms 0.6% PetscMallocAlign 0 0% 88.67% 23ms 0.57% VecCreate 0 0% 88.67% 22ms 0.55% PetscMemcpy (inline) 0 0% 88.67% 22ms 0.55% VecDuplicate_Seq 1ms 0.025% 88.69% 21ms 0.52% 8ms 0.2% 88.89% 21ms 0.52% AbstractCardiacCell::GetVoltage (partial-inline) 5ms 0.12% 89.01% 21ms 0.52% AbstractCardiacCell::SetVoltage (partial-inline)