File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3658ms, 86.56% of 4226ms total Dropped 474 nodes (cum <= 21.13ms) flat flat% sum% cum cum% 0 0% 0% 4217ms 99.79% __libc_start_call_main 0 0% 0% 4217ms 99.79% __libc_start_main_impl 0 0% 0% 4217ms 99.79% _start 0 0% 0% 4214ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4214ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4214ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4214ms 99.72% main 0 0% 0% 4175ms 98.79% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4175ms 98.79% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4175ms 98.79% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 2ms 0.047% 0.047% 4167ms 98.60% AbstractCardiacProblem::Solve 0 0% 0.047% 4147ms 98.13% AbstractDynamicLinearPdeSolver::Solve 17ms 0.4% 0.45% 2225ms 52.65% AbstractCardiacTissue::SolveCellSystems 0 0% 0.45% 1618ms 38.29% LinearSystem::Solve 0 0% 0.45% 1580ms 37.39% KSPSolve 7ms 0.17% 0.62% 1580ms 37.39% KSPSolve_Private 3ms 0.071% 0.69% 1528ms 36.16% KSPSolve_SYMMLQ 14ms 0.33% 1.02% 1254ms 29.67% AbstractCardiacCell::ComputeExceptVoltage 33ms 0.78% 1.80% 1080ms 25.56% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.047% 1.85% 989ms 23.40% PCApply 0 0% 1.85% 981ms 23.21% PCApply_BJacobi_Singleblock 48ms 1.14% 2.98% 888ms 21.01% EulerIvpOdeSolver::CalculateNextYValue 241ms 5.70% 8.68% 833ms 19.71% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 8.68% 817ms 19.33% KSPSolve_PREONLY (partial-inline) 345ms 8.16% 16.85% 784ms 18.55% __GI___exp 1ms 0.024% 16.87% 772ms 18.27% PCApply_ICC 2ms 0.047% 16.92% 771ms 18.24% MatSolve 762ms 18.03% 34.95% 765ms 18.10% MatSolve_SeqSBAIJ_1_NaturalOrdering 26ms 0.62% 35.57% 761ms 18.01% AbstractCardiacTissue::UpdateCaches (inline) 235ms 5.56% 41.13% 708ms 16.75% CellLuoRudy1991FromCellML::GetIIonic 0 0% 41.13% 702ms 16.61% KSP_PCApply.lto_priv.18 6ms 0.14% 41.27% 456ms 10.79% MatMult 4ms 0.095% 41.36% 445ms 10.53% MatMult_SeqAIJ 438ms 10.36% 51.73% 445ms 10.53% MatMult_SeqAIJ_Inode (inline) 385ms 9.11% 60.84% 414ms 9.80% __ieee754_exp_fma 1ms 0.024% 60.86% 350ms 8.28% KSPConvergedDefault 0 0% 60.86% 346ms 8.19% KSP_PCApply (partial-inline) 51ms 1.21% 62.07% 267ms 6.32% BidomainSolver::SetupLinearSystem 2ms 0.047% 62.12% 224ms 5.30% std::pow (inline) 22ms 0.52% 62.64% 222ms 5.25% __pow 22ms 0.52% 63.16% 200ms 4.73% __ieee754_pow_fma 62ms 1.47% 64.62% 148ms 3.50% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 64.67% 105ms 2.48% MatNullSpaceRemove 0 0% 64.67% 95ms 2.25% VecDuplicate 89ms 2.11% 66.78% 89ms 2.11% log_inline (inline) 80ms 1.89% 68.67% 85ms 2.01% exp_inline (inline) 42ms 0.99% 69.66% 71ms 1.68% TimeStepper::TimeStepper 71ms 1.68% 71.34% 71ms 1.68% ddot_ 16ms 0.38% 71.72% 66ms 1.56% MPI_Wtime 0 0% 71.72% 66ms 1.56% VecMDot 7ms 0.17% 71.89% 66ms 1.56% VecMXDot_Private 2ms 0.047% 71.94% 58ms 1.37% VecCopy 1ms 0.024% 71.96% 56ms 1.33% VecDot 10ms 0.24% 72.20% 54ms 1.28% PetscLogEventEndDefault 10ms 0.24% 72.43% 53ms 1.25% PetscLogEventBeginDefault 40ms 0.95% 73.38% 51ms 1.21% AbstractOneStepIvpOdeSolver::Solve 1ms 0.024% 73.40% 50ms 1.18% VecDestroy 2ms 0.047% 73.45% 50ms 1.18% VecDuplicate_Seq 50ms 1.18% 74.63% 50ms 1.18% [[vdso]] 0 0% 74.63% 50ms 1.18% __GI___clock_gettime 49ms 1.16% 75.79% 49ms 1.16% TimeStepper::CalculateNextTime 2ms 0.047% 75.84% 45ms 1.06% VecDuplicate_MPI 1ms 0.024% 75.86% 45ms 1.06% VecNorm 0 0% 75.86% 44ms 1.04% VecDot_Seq 0 0% 75.86% 44ms 1.04% VecXDot_Seq_Private.constprop.0 44ms 1.04% 76.90% 44ms 1.04% std::isinf (inline) 0 0% 76.90% 42ms 0.99% VecNorm_Seq 32ms 0.76% 77.66% 40ms 0.95% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 11ms 0.26% 77.92% 40ms 0.95% TimeStepper::AdvanceOneTimeStep 3ms 0.071% 77.99% 40ms 0.95% VecCreate 38ms 0.9% 78.89% 40ms 0.95% VecMDot_Seq 16ms 0.38% 79.27% 38ms 0.9% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 3ms 0.071% 79.34% 37ms 0.88% VecMAXPY 37ms 0.88% 80.22% 37ms 0.88% __memcpy_avx_unaligned_erms 4ms 0.095% 80.31% 36ms 0.85% AbstractCardiacCell::SetVoltage (partial-inline) 35ms 0.83% 81.14% 36ms 0.85% __ieee754_log_fma 0 0% 81.14% 35ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 81.14% 35ms 0.83% PMPI_Init_thread 0 0% 81.14% 35ms 0.83% PetscInitialize 0 0% 81.14% 35ms 0.83% PetscSetup::setUpWorld 0 0% 81.14% 35ms 0.83% PetscSetupUtils::CommonSetup 0 0% 81.14% 35ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 81.14% 35ms 0.83% ompi_mpi_init 34ms 0.8% 81.95% 34ms 0.8% [libchaste_heart.so] 7ms 0.17% 82.11% 33ms 0.78% PetscMallocA 14ms 0.33% 82.44% 33ms 0.78% top12 (inline) 4ms 0.095% 82.54% 31ms 0.73% 1ms 0.024% 82.56% 31ms 0.73% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 82.56% 31ms 0.73% PetscMemcpy (inline) 5ms 0.12% 82.68% 30ms 0.71% AbstractBidomainSolver::CheckCompatibilityCondition 2ms 0.047% 82.73% 30ms 0.71% KSP_RemoveNullSpace (inline) 2ms 0.047% 82.77% 30ms 0.71% VecAXPY 0 0% 82.77% 30ms 0.71% VecNorm_MPI 0 0% 82.77% 30ms 0.71% VecNorm_MPI_Default.constprop.0 28ms 0.66% 83.44% 28ms 0.66% DistributedVector::Iterator::operator!= 0 0% 83.44% 28ms 0.66% VecCopy_Seq (partial-inline) 7ms 0.17% 83.60% 28ms 0.66% VecSetType 23ms 0.54% 84.15% 27ms 0.64% VecMAXPY_Seq 0 0% 84.15% 26ms 0.62% PetscMallocAlign 18ms 0.43% 84.57% 25ms 0.59% AbstractParameterisedSystem::GetAnyVariable 25ms 0.59% 85.16% 25ms 0.59% [libm.so.6] 25ms 0.59% 85.75% 25ms 0.59% asuint64 (inline) 12ms 0.28% 86.04% 25ms 0.59% std::__cxx11::basic_string::basic_string 0 0% 86.04% 23ms 0.54% orte_init 20ms 0.47% 86.51% 22ms 0.52% AbstractParameterisedSystem::SetAnyVariable 2ms 0.047% 86.56% 22ms 0.52% PetscHeaderCreate_Private