File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3634ms, 87.78% of 4140ms total Dropped 489 nodes (cum <= 20.70ms) flat flat% sum% cum cum% 0 0% 0% 4131ms 99.78% __libc_start_call_main 0 0% 0% 4131ms 99.78% __libc_start_main_impl 0 0% 0% 4131ms 99.78% _start 0 0% 0% 4128ms 99.71% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4128ms 99.71% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4128ms 99.71% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4128ms 99.71% main 0 0% 0% 4091ms 98.82% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4091ms 98.82% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4091ms 98.82% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.024% 0.024% 4084ms 98.65% AbstractCardiacProblem::Solve 2ms 0.048% 0.072% 4074ms 98.41% AbstractDynamicLinearPdeSolver::Solve 16ms 0.39% 0.46% 2428ms 58.65% AbstractCardiacTissue::SolveCellSystems 39ms 0.94% 1.40% 1368ms 33.04% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.40% 1297ms 31.33% LinearSystem::Solve 0 0% 1.40% 1269ms 30.65% KSPSolve 7ms 0.17% 1.57% 1269ms 30.65% KSPSolve_Private 0 0% 1.57% 1217ms 29.40% KSPSolve_SYMMLQ 22ms 0.53% 2.10% 1161ms 28.04% AbstractOneStepIvpOdeSolver::InternalSolve 50ms 1.21% 3.31% 1008ms 24.35% EulerIvpOdeSolver::CalculateNextYValue 239ms 5.77% 9.08% 939ms 22.68% CellLuoRudy1991FromCellML::EvaluateYDerivatives 388ms 9.37% 18.45% 845ms 20.41% __GI___exp 40ms 0.97% 19.42% 804ms 19.42% AbstractCardiacTissue::UpdateCaches (inline) 273ms 6.59% 26.01% 731ms 17.66% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.048% 26.06% 726ms 17.54% PCApply 0 0% 26.06% 720ms 17.39% PCApply_BJacobi_Singleblock 2ms 0.048% 26.11% 607ms 14.66% KSPSolve_PREONLY 1ms 0.024% 26.14% 582ms 14.06% PCApply_ICC 1ms 0.024% 26.16% 581ms 14.03% MatSolve 576ms 13.91% 40.07% 578ms 13.96% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.07% 511ms 12.34% KSP_PCApply.lto_priv.18 1ms 0.024% 40.10% 452ms 10.92% MatMult 5ms 0.12% 40.22% 447ms 10.80% MatMult_SeqAIJ (partial-inline) 438ms 10.58% 50.80% 447ms 10.80% MatMult_SeqAIJ_Inode (inline) 403ms 9.73% 60.53% 435ms 10.51% __ieee754_exp_fma 53ms 1.28% 61.81% 312ms 7.54% BidomainSolver::SetupLinearSystem 2ms 0.048% 61.86% 288ms 6.96% KSPConvergedDefault 0 0% 61.86% 265ms 6.40% KSP_PCApply (partial-inline) 27ms 0.65% 62.51% 238ms 5.75% __pow 0 0% 62.51% 238ms 5.75% std::pow (inline) 18ms 0.43% 62.95% 211ms 5.10% __ieee754_pow_fma 85ms 2.05% 65.00% 190ms 4.59% CellLuoRudy1991FromCellML::VerifyStateVariables 117ms 2.83% 67.83% 117ms 2.83% log_inline (inline) 1ms 0.024% 67.85% 91ms 2.20% MatNullSpaceRemove 0 0% 67.85% 78ms 1.88% VecDuplicate 72ms 1.74% 69.59% 72ms 1.74% exp_inline (inline) 0 0% 69.59% 70ms 1.69% VecMDot 2ms 0.048% 69.64% 70ms 1.69% VecMXDot_Private 55ms 1.33% 70.97% 61ms 1.47% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 19ms 0.46% 71.43% 61ms 1.47% MPI_Wtime 57ms 1.38% 72.80% 57ms 1.38% ddot_ 52ms 1.26% 74.06% 54ms 1.30% __ieee754_log_fma 50ms 1.21% 75.27% 53ms 1.28% VecMDot_Seq 29ms 0.7% 75.97% 52ms 1.26% TimeStepper::TimeStepper 50ms 1.21% 77.17% 50ms 1.21% std::isinf (inline) 5ms 0.12% 77.29% 47ms 1.14% VecCopy 0 0% 77.29% 44ms 1.06% VecDot 7ms 0.17% 77.46% 42ms 1.01% PetscLogEventEndDefault 1ms 0.024% 77.49% 42ms 1.01% VecNorm 1ms 0.024% 77.51% 42ms 1.01% __GI___clock_gettime 2ms 0.048% 77.56% 41ms 0.99% VecDuplicate_MPI 41ms 0.99% 78.55% 41ms 0.99% [[vdso]] 4ms 0.097% 78.65% 40ms 0.97% PetscLogEventBeginDefault 11ms 0.27% 78.91% 39ms 0.94% AbstractCardiacCell::SetVoltage (partial-inline) 23ms 0.56% 79.47% 39ms 0.94% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 38ms 0.92% 80.39% 38ms 0.92% __memcpy_avx_unaligned_erms 1ms 0.024% 80.41% 37ms 0.89% VecDuplicate_Seq 21ms 0.51% 80.92% 36ms 0.87% AbstractOneStepIvpOdeSolver::Solve 2ms 0.048% 80.97% 36ms 0.87% VecCreate 0 0% 80.97% 36ms 0.87% VecDot_Seq 0 0% 80.97% 36ms 0.87% VecXDot_Seq_Private.constprop.0 1ms 0.024% 80.99% 35ms 0.85% VecAXPY 0 0% 80.99% 35ms 0.85% VecDestroy 0 0% 80.99% 35ms 0.85% VecNorm_Seq 34ms 0.82% 81.81% 34ms 0.82% [libchaste_heart.so] 16ms 0.39% 82.20% 33ms 0.8% top12 (inline) 0 0% 82.20% 32ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 82.20% 32ms 0.77% PetscInitialize 6ms 0.14% 82.34% 32ms 0.77% PetscMallocA 0 0% 82.34% 32ms 0.77% PetscSetup::setUpWorld 0 0% 82.34% 32ms 0.77% PetscSetupUtils::CommonSetup 0 0% 82.34% 32ms 0.77% PetscSetupUtils::InitialisePetsc 0 0% 82.34% 31ms 0.75% PMPI_Init_thread 0 0% 82.34% 31ms 0.75% ompi_mpi_init 11ms 0.27% 82.61% 31ms 0.75% std::__cxx11::basic_string::basic_string 30ms 0.72% 83.33% 31ms 0.75% std::vector::operator[] (inline) 1ms 0.024% 83.36% 30ms 0.72% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 83.36% 30ms 0.72% PetscMemcpy (inline) 7ms 0.17% 83.53% 29ms 0.7% AbstractBidomainSolver::CheckCompatibilityCondition 29ms 0.7% 84.23% 29ms 0.7% TimeStepper::CalculateNextTime 0 0% 84.23% 28ms 0.68% KSP_RemoveNullSpace (inline) 0 0% 84.23% 28ms 0.68% VecNorm_MPI 3ms 0.072% 84.30% 28ms 0.68% VecNorm_MPI_Default.constprop.0 2ms 0.048% 84.35% 27ms 0.65% VecAXPY_Seq 0 0% 84.35% 27ms 0.65% VecCopy_Seq (partial-inline) 0 0% 84.35% 26ms 0.63% PetscMallocAlign 22ms 0.53% 84.88% 25ms 0.6% AbstractParameterisedSystem::SetAnyVariable 0 0% 84.88% 24ms 0.58% 11ms 0.27% 85.14% 24ms 0.58% AbstractCardiacCell::GetVoltage (partial-inline) 24ms 0.58% 85.72% 24ms 0.58% daxpy_ 23ms 0.56% 86.28% 23ms 0.56% RegularStimulus::GetStimulus 11ms 0.27% 86.55% 22ms 0.53% TimeStepper::AdvanceOneTimeStep 22ms 0.53% 87.08% 22ms 0.53% [libm.so.6] 5ms 0.12% 87.20% 22ms 0.53% __GI___libc_malloc 22ms 0.53% 87.73% 22ms 0.53% asuint64 (inline) 2ms 0.048% 87.78% 21ms 0.51% VecCreate_MPI_Private 0 0% 87.78% 21ms 0.51% orte_init