File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 2235ms, 88.10% of 2537ms total Dropped 379 nodes (cum <= 12.69ms) flat flat% sum% cum cum% 0 0% 0% 2527ms 99.61% __libc_start_call_main 0 0% 0% 2527ms 99.61% __libc_start_main_impl 0 0% 0% 2527ms 99.61% _start 0 0% 0% 2525ms 99.53% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 2525ms 99.53% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 2525ms 99.53% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 2525ms 99.53% main 0 0% 0% 2487ms 98.03% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 2487ms 98.03% CxxTest::TestRunner::runTest (inline) 0 0% 0% 2487ms 98.03% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 2482ms 97.83% AbstractCardiacProblem::Solve 2ms 0.079% 0.079% 2472ms 97.44% AbstractDynamicLinearPdeSolver::Solve 8ms 0.32% 0.39% 1303ms 51.36% AbstractCardiacTissue::SolveCellSystems 0 0% 0.39% 965ms 38.04% LinearSystem::Solve 0 0% 0.39% 943ms 37.17% KSPSolve 3ms 0.12% 0.51% 943ms 37.17% KSPSolve_Private 0 0% 0.51% 910ms 35.87% KSPSolve_SYMMLQ 9ms 0.35% 0.87% 725ms 28.58% AbstractCardiacCell::ComputeExceptVoltage 9ms 0.35% 1.22% 626ms 24.67% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.22% 566ms 22.31% PCApply 0 0% 1.22% 564ms 22.23% PCApply_BJacobi_Singleblock 41ms 1.62% 2.84% 532ms 20.97% EulerIvpOdeSolver::CalculateNextYValue 121ms 4.77% 7.61% 490ms 19.31% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 7.61% 486ms 19.16% KSPSolve_PREONLY (partial-inline) 5ms 0.2% 7.80% 478ms 18.84% AbstractCardiacTissue::UpdateCaches (inline) 2ms 0.079% 7.88% 464ms 18.29% MatSolve 0 0% 7.88% 464ms 18.29% PCApply_ICC 454ms 17.90% 25.78% 457ms 18.01% MatSolve_SeqSBAIJ_1_NaturalOrdering 163ms 6.42% 32.20% 454ms 17.90% __GI___exp 151ms 5.95% 38.16% 452ms 17.82% CellLuoRudy1991FromCellML::GetIIonic 0 0% 38.16% 430ms 16.95% KSP_PCApply.lto_priv.18 2ms 0.079% 38.23% 295ms 11.63% MatMult 5ms 0.2% 38.43% 291ms 11.47% MatMult_SeqAIJ (partial-inline) 284ms 11.19% 49.63% 290ms 11.43% MatMult_SeqAIJ_Inode (inline) 233ms 9.18% 58.81% 271ms 10.68% __ieee754_exp_fma 25ms 0.99% 59.80% 180ms 7.09% __pow 0 0% 59.80% 180ms 7.09% std::pow (inline) 0 0% 59.80% 177ms 6.98% KSPConvergedDefault 28ms 1.10% 60.90% 173ms 6.82% BidomainSolver::SetupLinearSystem 0 0% 60.90% 169ms 6.66% KSP_PCApply (partial-inline) 15ms 0.59% 61.49% 155ms 6.11% __ieee754_pow_fma 0 0% 61.49% 76ms 3.00% MatNullSpaceRemove 73ms 2.88% 64.37% 73ms 2.88% log_inline (inline) 58ms 2.29% 66.65% 66ms 2.60% exp_inline (inline) 21ms 0.83% 67.48% 62ms 2.44% MPI_Wtime 29ms 1.14% 68.62% 59ms 2.33% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 68.62% 52ms 2.05% VecMDot 1ms 0.039% 68.66% 52ms 2.05% VecMXDot_Private 6ms 0.24% 68.90% 47ms 1.85% PetscLogEventEndDefault 0 0% 68.90% 41ms 1.62% VecDuplicate 3ms 0.12% 69.02% 41ms 1.62% __GI___clock_gettime 38ms 1.50% 70.52% 39ms 1.54% VecMDot_Seq 39ms 1.54% 72.05% 39ms 1.54% [[vdso]] 27ms 1.06% 73.12% 39ms 1.54% top12 (inline) 5ms 0.2% 73.31% 37ms 1.46% VecCopy 7ms 0.28% 73.59% 35ms 1.38% PetscLogEventBeginDefault 0 0% 73.59% 34ms 1.34% CxxTest::RealWorldDescription::setUp 0 0% 73.59% 34ms 1.34% PMPI_Init_thread 0 0% 73.59% 34ms 1.34% PetscInitialize 0 0% 73.59% 34ms 1.34% PetscSetup::setUpWorld 0 0% 73.59% 34ms 1.34% PetscSetupUtils::CommonSetup 0 0% 73.59% 34ms 1.34% PetscSetupUtils::InitialisePetsc 0 0% 73.59% 34ms 1.34% ompi_mpi_init 3ms 0.12% 73.71% 32ms 1.26% 32ms 1.26% 74.97% 32ms 1.26% [libchaste_heart.so] 32ms 1.26% 76.23% 32ms 1.26% ddot_ 10ms 0.39% 76.63% 30ms 1.18% AbstractCardiacCell::SetVoltage (partial-inline) 6ms 0.24% 76.86% 27ms 1.06% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 76.86% 27ms 1.06% AbstractBidomainSolver::FinaliseLinearSystem 11ms 0.43% 77.30% 26ms 1.02% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 77.30% 25ms 0.99% VecAXPY 0 0% 77.30% 25ms 0.99% VecNorm 18ms 0.71% 78.01% 24ms 0.95% TimeStepper::TimeStepper 1ms 0.039% 78.04% 24ms 0.95% VecDestroy 0 0% 78.04% 24ms 0.95% VecDuplicate_MPI 2ms 0.079% 78.12% 24ms 0.95% VecMAXPY 14ms 0.55% 78.68% 23ms 0.91% AbstractOneStepIvpOdeSolver::Solve 0 0% 78.68% 23ms 0.91% VecDot 0 0% 78.68% 23ms 0.91% orte_init 22ms 0.87% 79.54% 22ms 0.87% [libchaste_ode.so] 14ms 0.55% 80.09% 21ms 0.83% AbstractParameterisedSystem::GetAnyVariable 14ms 0.55% 80.65% 20ms 0.79% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 20ms 0.79% 81.43% 20ms 0.79% [libm.so.6] 20ms 0.79% 82.22% 20ms 0.79% __memcpy_avx512_unaligned_erms 20ms 0.79% 83.01% 20ms 0.79% asuint64 (inline) 1ms 0.039% 83.05% 19ms 0.75% PetscMallocA 0 0% 83.05% 19ms 0.75% VecDot_Seq 0 0% 83.05% 19ms 0.75% VecNorm_Seq 0 0% 83.05% 19ms 0.75% VecXDot_Seq_Private.constprop.0 19ms 0.75% 83.80% 19ms 0.75% __ieee754_log_fma 18ms 0.71% 84.51% 18ms 0.71% DistributedVector::Iterator::operator!= 0 0% 84.51% 18ms 0.71% PetscMallocAlign 18ms 0.71% 85.22% 18ms 0.71% RegularStimulus::GetStimulus 0 0% 85.22% 18ms 0.71% VecCreate 0 0% 85.22% 17ms 0.67% VecDuplicate_Seq 8ms 0.32% 85.53% 17ms 0.67% std::__cxx11::basic_string::basic_string 0 0% 85.53% 16ms 0.63% PetscMemcpy (inline) 1ms 0.039% 85.57% 16ms 0.63% VecAXPY_Seq 0 0% 85.57% 16ms 0.63% __GI__dl_catch_exception 0 0% 85.57% 16ms 0.63% _dl_catch_error 0 0% 85.57% 16ms 0.63% _dlerror_run 14ms 0.55% 86.13% 15ms 0.59% AbstractParameterisedSystem::SetAnyVariable 0 0% 86.13% 15ms 0.59% VecNorm_MPI 0 0% 86.13% 15ms 0.59% VecNorm_MPI_Default.constprop.0 5ms 0.2% 86.32% 15ms 0.59% __GI___libc_malloc 0 0% 86.32% 15ms 0.59% __posix_memalign 0 0% 86.32% 15ms 0.59% hwloc_topology_load 0 0% 86.32% 15ms 0.59% opal_stackframe_output 14ms 0.55% 86.87% 14ms 0.55% ReplicatableVector::operator[] 0 0% 86.87% 14ms 0.55% VecCopy_Seq (partial-inline) 0 0% 86.87% 14ms 0.55% VecCreate_MPI_Private 11ms 0.43% 87.31% 14ms 0.55% VecMAXPY_Seq 0 0% 87.31% 14ms 0.55% hwloc_linux_get_tid_last_cpu_location 7ms 0.28% 87.58% 13ms 0.51% TimeStepper::AdvanceOneTimeStep 13ms 0.51% 88.10% 13ms 0.51% daxpy_ 0 0% 88.10% 13ms 0.51% mca_base_framework_open