File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3627ms, 85.70% of 4232ms total Dropped 491 nodes (cum <= 21.16ms) flat flat% sum% cum cum% 0 0% 0% 4223ms 99.79% __libc_start_call_main 0 0% 0% 4223ms 99.79% __libc_start_main_impl 0 0% 0% 4223ms 99.79% _start 0 0% 0% 4220ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4220ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4220ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4220ms 99.72% main 0 0% 0% 4178ms 98.72% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4178ms 98.72% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4178ms 98.72% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4170ms 98.53% AbstractCardiacProblem::Solve 4ms 0.095% 0.095% 4151ms 98.09% AbstractDynamicLinearPdeSolver::Solve 21ms 0.5% 0.59% 2253ms 53.24% AbstractCardiacTissue::SolveCellSystems 2ms 0.047% 0.64% 1518ms 35.87% LinearSystem::Solve 0 0% 0.64% 1475ms 34.85% KSPSolve 6ms 0.14% 0.78% 1475ms 34.85% KSPSolve_Private 1ms 0.024% 0.8% 1415ms 33.44% KSPSolve_SYMMLQ 18ms 0.43% 1.23% 1287ms 30.41% AbstractCardiacCell::ComputeExceptVoltage 31ms 0.73% 1.96% 1108ms 26.18% AbstractOneStepIvpOdeSolver::InternalSolve 57ms 1.35% 3.31% 926ms 21.88% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.024% 3.33% 859ms 20.30% PCApply 2ms 0.047% 3.38% 846ms 19.99% PCApply_BJacobi_Singleblock 230ms 5.43% 8.81% 817ms 19.31% CellLuoRudy1991FromCellML::EvaluateYDerivatives 17ms 0.4% 9.22% 780ms 18.43% AbstractCardiacTissue::UpdateCaches (inline) 385ms 9.10% 18.31% 763ms 18.03% __GI___exp 234ms 5.53% 23.84% 723ms 17.08% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 23.87% 711ms 16.80% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 23.89% 666ms 15.74% PCApply_ICC 4ms 0.095% 23.98% 665ms 15.71% MatSolve 650ms 15.36% 39.34% 656ms 15.50% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 39.34% 633ms 14.96% KSP_PCApply.lto_priv.18 1ms 0.024% 39.37% 466ms 11.01% MatMult 10ms 0.24% 39.60% 456ms 10.78% MatMult_SeqAIJ (partial-inline) 444ms 10.49% 50.09% 456ms 10.78% MatMult_SeqAIJ_Inode (inline) 297ms 7.02% 57.11% 356ms 8.41% __ieee754_exp_fma 71ms 1.68% 58.79% 334ms 7.89% BidomainSolver::SetupLinearSystem 2ms 0.047% 58.84% 313ms 7.40% KSPConvergedDefault 0 0% 58.84% 289ms 6.83% KSP_PCApply (partial-inline) 3ms 0.071% 58.91% 243ms 5.74% std::pow (inline) 27ms 0.64% 59.55% 240ms 5.67% __pow 17ms 0.4% 59.95% 213ms 5.03% __ieee754_pow_fma 2ms 0.047% 60.00% 122ms 2.88% MatNullSpaceRemove 57ms 1.35% 61.34% 116ms 2.74% CellLuoRudy1991FromCellML::VerifyStateVariables 106ms 2.50% 63.85% 106ms 2.50% log_inline (inline) 24ms 0.57% 64.41% 90ms 2.13% MPI_Wtime 0 0% 64.41% 86ms 2.03% VecDuplicate 81ms 1.91% 66.33% 86ms 2.03% exp_inline (inline) 73ms 1.72% 68.05% 73ms 1.72% ddot_ 14ms 0.33% 68.38% 69ms 1.63% PetscLogEventEndDefault 0 0% 68.38% 68ms 1.61% VecMDot 3ms 0.071% 68.45% 68ms 1.61% VecMXDot_Private 2ms 0.047% 68.50% 66ms 1.56% __GI___clock_gettime 31ms 0.73% 69.23% 65ms 1.54% TimeStepper::TimeStepper 64ms 1.51% 70.75% 64ms 1.51% [[vdso]] 17ms 0.4% 71.15% 61ms 1.44% PetscLogEventBeginDefault 16ms 0.38% 71.53% 59ms 1.39% AbstractCardiacCell::SetVoltage (partial-inline) 1ms 0.024% 71.55% 58ms 1.37% VecDestroy 57ms 1.35% 72.90% 57ms 1.35% [libchaste_heart.so] 2ms 0.047% 72.94% 52ms 1.23% VecMAXPY 2ms 0.047% 72.99% 51ms 1.21% VecCopy 1ms 0.024% 73.02% 51ms 1.21% VecNorm 1ms 0.024% 73.04% 50ms 1.18% VecDot 1ms 0.024% 73.06% 48ms 1.13% VecDuplicate_MPI 45ms 1.06% 74.13% 48ms 1.13% VecMDot_Seq 32ms 0.76% 74.88% 48ms 1.13% top12 (inline) 0 0% 74.88% 47ms 1.11% VecDot_Seq 1ms 0.024% 74.91% 47ms 1.11% VecXDot_Seq_Private.constprop.0 0 0% 74.91% 42ms 0.99% VecNorm_Seq 23ms 0.54% 75.45% 41ms 0.97% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 41ms 0.97% 76.42% 41ms 0.97% TimeStepper::CalculateNextTime 41ms 0.97% 77.39% 41ms 0.97% __memcpy_avx_unaligned_erms 40ms 0.95% 78.33% 40ms 0.95% non-virtual thunk to CellLuoRudy1991FromCellML::EvaluateYDerivatives(double, std::vector const&, std::vector&) 28ms 0.66% 78.99% 39ms 0.92% AbstractOneStepIvpOdeSolver::Solve 31ms 0.73% 79.73% 39ms 0.92% AbstractParameterisedSystem::SetAnyVariable 4ms 0.095% 79.82% 38ms 0.9% PetscMallocA 0 0% 79.82% 38ms 0.9% PetscTimeAdd (inline) 0 0% 79.82% 38ms 0.9% VecDuplicate_Seq 0 0% 79.82% 37ms 0.87% CxxTest::RealWorldDescription::setUp 0 0% 79.82% 37ms 0.87% PetscInitialize 0 0% 79.82% 37ms 0.87% PetscSetup::setUpWorld 0 0% 79.82% 37ms 0.87% PetscSetupUtils::CommonSetup 0 0% 79.82% 37ms 0.87% PetscSetupUtils::InitialisePetsc 2ms 0.047% 79.87% 37ms 0.87% VecAXPY 37ms 0.87% 80.74% 37ms 0.87% asuint64 (inline) 33ms 0.78% 81.52% 36ms 0.85% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 81.52% 36ms 0.85% PMPI_Init_thread 1ms 0.024% 81.55% 36ms 0.85% PetscTimeSubtract (inline) 0 0% 81.55% 36ms 0.85% VecCreate 0 0% 81.55% 36ms 0.85% ompi_mpi_init 1ms 0.024% 81.57% 34ms 0.8% AbstractBidomainSolver::FinaliseLinearSystem 2ms 0.047% 81.62% 34ms 0.8% PetscMallocAlign 11ms 0.26% 81.88% 33ms 0.78% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.88% 33ms 0.78% PetscMemcpy (inline) 19ms 0.45% 82.33% 33ms 0.78% TimeStepper::AdvanceOneTimeStep 2ms 0.047% 82.37% 32ms 0.76% 30ms 0.71% 83.08% 30ms 0.71% VecMAXPY_Seq 13ms 0.31% 83.39% 30ms 0.71% std::__cxx11::basic_string::basic_string 0 0% 83.39% 29ms 0.69% VecNorm_MPI 1ms 0.024% 83.41% 29ms 0.69% VecNorm_MPI_Default.constprop.0 26ms 0.61% 84.03% 27ms 0.64% __ieee754_log_fma 25ms 0.59% 84.62% 25ms 0.59% std::vector::operator[] (inline) 18ms 0.43% 85.04% 24ms 0.57% AbstractParameterisedSystem::GetAnyVariable 3ms 0.071% 85.11% 24ms 0.57% PetscHeaderDestroy_Private 0 0% 85.11% 24ms 0.57% VecAXPY_Seq 0 0% 85.11% 24ms 0.57% VecCopy_Seq (partial-inline) 2ms 0.047% 85.16% 24ms 0.57% VecSetType 0 0% 85.16% 24ms 0.57% orte_init 1ms 0.024% 85.18% 23ms 0.54% VecCreate_MPI_Private 0 0% 85.18% 22ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 22ms 0.52% 85.70% 22ms 0.52% [libm.so.6]