File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3692ms, 88.77% of 4159ms total Dropped 458 nodes (cum <= 20.80ms) flat flat% sum% cum cum% 0 0% 0% 4149ms 99.76% __libc_start_call_main 0 0% 0% 4149ms 99.76% __libc_start_main_impl 0 0% 0% 4149ms 99.76% _start 0 0% 0% 4146ms 99.69% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4146ms 99.69% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4146ms 99.69% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4146ms 99.69% main 0 0% 0% 4108ms 98.77% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4108ms 98.77% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4108ms 98.77% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4101ms 98.61% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4091ms 98.36% AbstractDynamicLinearPdeSolver::Solve 13ms 0.31% 0.34% 2546ms 61.22% AbstractCardiacTissue::SolveCellSystems 44ms 1.06% 1.39% 1512ms 36.35% AbstractCardiacCell::ComputeExceptVoltage 28ms 0.67% 2.07% 1298ms 31.21% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.024% 2.09% 1188ms 28.56% LinearSystem::Solve 0 0% 2.09% 1146ms 27.55% KSPSolve 3ms 0.072% 2.16% 1146ms 27.55% KSPSolve_Private 2ms 0.048% 2.21% 1108ms 26.64% KSPSolve_SYMMLQ 78ms 1.88% 4.09% 1103ms 26.52% EulerIvpOdeSolver::CalculateNextYValue 298ms 7.17% 11.25% 1014ms 24.38% CellLuoRudy1991FromCellML::EvaluateYDerivatives 411ms 9.88% 21.13% 868ms 20.87% __GI___exp 26ms 0.63% 21.76% 814ms 19.57% AbstractCardiacTissue::UpdateCaches (inline) 274ms 6.59% 28.35% 752ms 18.08% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.048% 28.40% 681ms 16.37% PCApply 1ms 0.024% 28.42% 673ms 16.18% PCApply_BJacobi_Singleblock 1ms 0.024% 28.44% 548ms 13.18% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 28.47% 524ms 12.60% PCApply_ICC 2ms 0.048% 28.52% 523ms 12.58% MatSolve 513ms 12.33% 40.85% 514ms 12.36% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.85% 504ms 12.12% KSP_PCApply.lto_priv.18 406ms 9.76% 50.61% 433ms 10.41% __ieee754_exp_fma 4ms 0.096% 50.71% 407ms 9.79% MatMult 4ms 0.096% 50.81% 399ms 9.59% MatMult_SeqAIJ 393ms 9.45% 60.25% 399ms 9.59% MatMult_SeqAIJ_Inode (inline) 65ms 1.56% 61.82% 310ms 7.45% BidomainSolver::SetupLinearSystem 2ms 0.048% 61.87% 251ms 6.04% std::pow (inline) 17ms 0.41% 62.27% 249ms 5.99% __pow 1ms 0.024% 62.30% 235ms 5.65% KSPConvergedDefault 26ms 0.63% 62.92% 231ms 5.55% __ieee754_pow_fma 0 0% 62.92% 220ms 5.29% KSP_PCApply (partial-inline) 81ms 1.95% 64.87% 162ms 3.90% CellLuoRudy1991FromCellML::VerifyStateVariables 113ms 2.72% 67.59% 113ms 2.72% log_inline (inline) 0 0% 67.59% 88ms 2.12% MatNullSpaceRemove 86ms 2.07% 69.66% 88ms 2.12% exp_inline (inline) 0 0% 69.66% 87ms 2.09% VecDuplicate 39ms 0.94% 70.59% 79ms 1.90% TimeStepper::TimeStepper 16ms 0.38% 70.98% 65ms 1.56% MPI_Wtime 55ms 1.32% 72.30% 55ms 1.32% TimeStepper::CalculateNextTime 0 0% 72.30% 54ms 1.30% VecMDot 6ms 0.14% 72.45% 54ms 1.30% VecMXDot_Private 51ms 1.23% 73.67% 52ms 1.25% __ieee754_log_fma 40ms 0.96% 74.63% 50ms 1.20% AbstractOneStepIvpOdeSolver::Solve 3ms 0.072% 74.71% 49ms 1.18% __GI___clock_gettime 49ms 1.18% 75.88% 49ms 1.18% ddot_ 48ms 1.15% 77.04% 48ms 1.15% [libchaste_heart.so] 7ms 0.17% 77.21% 47ms 1.13% PetscLogEventBeginDefault 1ms 0.024% 77.23% 46ms 1.11% VecDuplicate_MPI 46ms 1.11% 78.34% 46ms 1.11% [[vdso]] 11ms 0.26% 78.60% 45ms 1.08% AbstractCardiacCell::SetVoltage (partial-inline) 22ms 0.53% 79.13% 45ms 1.08% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 6ms 0.14% 79.27% 44ms 1.06% PetscMallocA 11ms 0.26% 79.54% 41ms 0.99% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 79.54% 41ms 0.99% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 79.54% 41ms 0.99% VecDuplicate_Seq 38ms 0.91% 80.45% 39ms 0.94% VecMDot_Seq 32ms 0.77% 81.22% 38ms 0.91% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.024% 81.25% 38ms 0.91% PetscMallocAlign (partial-inline) 6ms 0.14% 81.39% 35ms 0.84% PetscLogEventEndDefault 0 0% 81.39% 35ms 0.84% VecDot 0 0% 81.39% 34ms 0.82% CxxTest::RealWorldDescription::setUp 0 0% 81.39% 34ms 0.82% PetscInitialize 0 0% 81.39% 34ms 0.82% PetscSetup::setUpWorld 0 0% 81.39% 34ms 0.82% PetscSetupUtils::CommonSetup 0 0% 81.39% 34ms 0.82% PetscSetupUtils::InitialisePetsc 11ms 0.26% 81.65% 34ms 0.82% TimeStepper::AdvanceOneTimeStep 0 0% 81.65% 34ms 0.82% VecDestroy 5ms 0.12% 81.77% 34ms 0.82% VecMAXPY 0 0% 81.77% 33ms 0.79% PMPI_Init_thread 1ms 0.024% 81.80% 33ms 0.79% VecAXPY 0 0% 81.80% 33ms 0.79% VecDot_Seq 0 0% 81.80% 33ms 0.79% VecXDot_Seq_Private.constprop.0 0 0% 81.80% 33ms 0.79% ompi_mpi_init 3ms 0.072% 81.87% 32ms 0.77% VecCopy 0 0% 81.87% 32ms 0.77% VecNorm 9ms 0.22% 82.09% 32ms 0.77% top12 (inline) 2ms 0.048% 82.14% 30ms 0.72% VecNorm_Seq 1ms 0.024% 82.16% 29ms 0.7% VecCreate_MPI_Private 29ms 0.7% 82.86% 29ms 0.7% std::isinf (inline) 2ms 0.048% 82.90% 28ms 0.67% VecCreate 2ms 0.048% 82.95% 27ms 0.65% 11ms 0.26% 83.22% 27ms 0.65% std::__cxx11::basic_string::basic_string 20ms 0.48% 83.70% 25ms 0.6% AbstractParameterisedSystem::GetAnyVariable 25ms 0.6% 84.30% 25ms 0.6% DistributedVector::Iterator::operator!= 2ms 0.048% 84.35% 25ms 0.6% VecSetType 25ms 0.6% 84.95% 25ms 0.6% [libm.so.6] 25ms 0.6% 85.55% 25ms 0.6% asuint64 (inline) 24ms 0.58% 86.13% 24ms 0.58% AbstractParameterisedSystem::SetAnyVariable 0 0% 86.13% 24ms 0.58% VecAXPY_Seq 0 0% 86.13% 23ms 0.55% PetscMemzero (inline) 23ms 0.55% 86.68% 23ms 0.55% [libchaste_ode.so] 0 0% 86.68% 23ms 0.55% orte_init 0 0% 86.68% 22ms 0.53% VecNorm_MPI 0 0% 86.68% 22ms 0.53% VecNorm_MPI_Default.constprop.0 22ms 0.53% 87.21% 22ms 0.53% __memcpy_avx_unaligned_erms 22ms 0.53% 87.74% 22ms 0.53% __memset_avx2_unaligned_erms 22ms 0.53% 88.27% 22ms 0.53% std::vector::operator[] (inline) 21ms 0.5% 88.77% 21ms 0.5% RegularStimulus::GetStimulus