File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3652ms, 86.66% of 4214ms total Dropped 466 nodes (cum <= 21.07ms) flat flat% sum% cum cum% 0 0% 0% 4206ms 99.81% __libc_start_call_main 0 0% 0% 4206ms 99.81% __libc_start_main_impl 0 0% 0% 4206ms 99.81% _start 0 0% 0% 4203ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4203ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4203ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4203ms 99.74% main 0 0% 0% 4165ms 98.84% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4165ms 98.84% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4165ms 98.84% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4157ms 98.65% AbstractCardiacProblem::Solve 2ms 0.047% 0.047% 4141ms 98.27% AbstractDynamicLinearPdeSolver::Solve 17ms 0.4% 0.45% 2245ms 53.27% AbstractCardiacTissue::SolveCellSystems 0 0% 0.45% 1588ms 37.68% LinearSystem::Solve 0 0% 0.45% 1534ms 36.40% KSPSolve 4ms 0.095% 0.55% 1534ms 36.40% KSPSolve_Private 2ms 0.047% 0.59% 1479ms 35.10% KSPSolve_SYMMLQ 12ms 0.28% 0.88% 1294ms 30.71% AbstractCardiacCell::ComputeExceptVoltage 30ms 0.71% 1.59% 1134ms 26.91% AbstractOneStepIvpOdeSolver::InternalSolve 3ms 0.071% 1.66% 946ms 22.45% PCApply 0 0% 1.66% 941ms 22.33% PCApply_BJacobi_Singleblock 57ms 1.35% 3.01% 926ms 21.97% EulerIvpOdeSolver::CalculateNextYValue 253ms 6.00% 9.02% 850ms 20.17% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 9.02% 796ms 18.89% KSPSolve_PREONLY (partial-inline) 361ms 8.57% 17.58% 759ms 18.01% __GI___exp 2ms 0.047% 17.63% 748ms 17.75% PCApply_ICC 2ms 0.047% 17.68% 746ms 17.70% MatSolve 732ms 17.37% 35.05% 738ms 17.51% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 22ms 0.52% 35.57% 724ms 17.18% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 35.57% 681ms 16.16% KSP_PCApply.lto_priv.18 242ms 5.74% 41.31% 668ms 15.85% CellLuoRudy1991FromCellML::GetIIonic 5ms 0.12% 41.43% 432ms 10.25% MatMult 33ms 0.78% 42.22% 419ms 9.94% MatMult_SeqAIJ 382ms 9.07% 51.28% 390ms 9.25% MatMult_SeqAIJ_Inode (inline) 348ms 8.26% 59.54% 381ms 9.04% __ieee754_exp_fma 2ms 0.047% 59.59% 345ms 8.19% KSPConvergedDefault 0 0% 59.59% 325ms 7.71% KSP_PCApply (partial-inline) 49ms 1.16% 60.75% 272ms 6.45% BidomainSolver::SetupLinearSystem 3ms 0.071% 60.82% 199ms 4.72% std::pow (inline) 18ms 0.43% 61.25% 196ms 4.65% __pow 76ms 1.80% 63.05% 181ms 4.30% CellLuoRudy1991FromCellML::VerifyStateVariables 15ms 0.36% 63.41% 178ms 4.22% __ieee754_pow_fma 3ms 0.071% 63.48% 126ms 2.99% MatNullSpaceRemove 0 0% 63.48% 101ms 2.40% VecDuplicate 50ms 1.19% 64.67% 99ms 2.35% TimeStepper::TimeStepper 23ms 0.55% 65.21% 88ms 2.09% MPI_Wtime 81ms 1.92% 67.13% 81ms 1.92% log_inline (inline) 72ms 1.71% 68.84% 73ms 1.73% exp_inline (inline) 0 0% 68.84% 72ms 1.71% VecMDot 7ms 0.17% 69.01% 72ms 1.71% VecMXDot_Private 5ms 0.12% 69.13% 65ms 1.54% __GI___clock_gettime 65ms 1.54% 70.67% 65ms 1.54% ddot_ 5ms 0.12% 70.79% 63ms 1.50% VecCopy 2ms 0.047% 70.84% 60ms 1.42% VecDestroy 60ms 1.42% 72.26% 60ms 1.42% [[vdso]] 6ms 0.14% 72.40% 55ms 1.31% PetscLogEventEndDefault 1ms 0.024% 72.43% 55ms 1.31% VecDot 1ms 0.024% 72.45% 52ms 1.23% VecDuplicate_MPI 7ms 0.17% 72.62% 51ms 1.21% VecMAXPY 7ms 0.17% 72.78% 50ms 1.19% PetscMallocA 50ms 1.19% 73.97% 50ms 1.19% TimeStepper::CalculateNextTime 46ms 1.09% 75.06% 50ms 1.19% VecMDot_Seq 0 0% 75.06% 49ms 1.16% VecDuplicate_Seq 40ms 0.95% 76.01% 48ms 1.14% AbstractOneStepIvpOdeSolver::Solve 0 0% 76.01% 46ms 1.09% VecDot_Seq 0 0% 76.01% 46ms 1.09% VecXDot_Seq_Private.constprop.0 1ms 0.024% 76.03% 45ms 1.07% VecNorm 9ms 0.21% 76.25% 44ms 1.04% PetscLogEventBeginDefault 4ms 0.095% 76.34% 43ms 1.02% PetscMallocAlign 43ms 1.02% 77.36% 43ms 1.02% std::isinf (inline) 40ms 0.95% 78.31% 40ms 0.95% __memcpy_avx_unaligned_erms 3ms 0.071% 78.38% 39ms 0.93% VecCreate 1ms 0.024% 78.41% 39ms 0.93% VecNorm_Seq 39ms 0.93% 79.33% 39ms 0.93% [libchaste_heart.so] 39ms 0.93% 80.26% 39ms 0.93% __ieee754_log_fma 33ms 0.78% 81.04% 38ms 0.9% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 19ms 0.45% 81.49% 37ms 0.88% std::__cxx11::basic_string::basic_string 17ms 0.4% 81.89% 37ms 0.88% top12 (inline) 0 0% 81.89% 36ms 0.85% PetscMemcpy (inline) 19ms 0.45% 82.34% 35ms 0.83% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 4ms 0.095% 82.44% 35ms 0.83% VecAXPY 4ms 0.095% 82.53% 34ms 0.81% VecCreate_MPI_Private 0 0% 82.53% 33ms 0.78% CxxTest::RealWorldDescription::setUp 0 0% 82.53% 33ms 0.78% PetscInitialize 0 0% 82.53% 33ms 0.78% PetscSetup::setUpWorld 0 0% 82.53% 33ms 0.78% PetscSetupUtils::CommonSetup 0 0% 82.53% 33ms 0.78% PetscSetupUtils::InitialisePetsc 1ms 0.024% 82.56% 33ms 0.78% VecCopy_Seq (partial-inline) 0 0% 82.56% 32ms 0.76% PMPI_Init_thread 0 0% 82.56% 32ms 0.76% ompi_mpi_init 5ms 0.12% 82.68% 30ms 0.71% PetscHeaderDestroy_Private 28ms 0.66% 83.34% 30ms 0.71% VecMAXPY_Seq 0 0% 83.34% 30ms 0.71% VecNorm_MPI 0 0% 83.34% 30ms 0.71% VecNorm_MPI_Default.constprop.0 0 0% 83.34% 29ms 0.69% 8ms 0.19% 83.53% 29ms 0.69% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.53% 29ms 0.69% AbstractBidomainSolver::FinaliseLinearSystem 5ms 0.12% 83.65% 29ms 0.69% AbstractCardiacCell::SetVoltage (partial-inline) 21ms 0.5% 84.15% 29ms 0.69% TimeStepper::AdvanceOneTimeStep 27ms 0.64% 84.79% 27ms 0.64% DistributedVector::Iterator::operator!= 27ms 0.64% 85.43% 27ms 0.64% std::vector::operator[] (inline) 26ms 0.62% 86.05% 26ms 0.62% asuint64 (inline) 1ms 0.024% 86.07% 25ms 0.59% VecSetType 0 0% 86.07% 23ms 0.55% orte_init 22ms 0.52% 86.59% 22ms 0.52% RegularStimulus::GetStimulus 1ms 0.024% 86.62% 22ms 0.52% VecAXPY_Seq 2ms 0.047% 86.66% 22ms 0.52% __posix_memalign