File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3624ms, 88.33% of 4103ms total Dropped 445 nodes (cum <= 20.52ms) flat flat% sum% cum cum% 0 0% 0% 4095ms 99.81% __libc_start_call_main 0 0% 0% 4095ms 99.81% __libc_start_main_impl 0 0% 0% 4095ms 99.81% _start 0 0% 0% 4092ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4092ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4092ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4092ms 99.73% main 0 0% 0% 4055ms 98.83% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4055ms 98.83% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4055ms 98.83% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4050ms 98.71% AbstractCardiacProblem::Solve 0 0% 0% 4040ms 98.46% AbstractDynamicLinearPdeSolver::Solve 9ms 0.22% 0.22% 1980ms 48.26% AbstractCardiacTissue::SolveCellSystems 0 0% 0.22% 1664ms 40.56% LinearSystem::Solve 0 0% 0.22% 1637ms 39.90% KSPSolve 5ms 0.12% 0.34% 1637ms 39.90% KSPSolve_Private 3ms 0.073% 0.41% 1590ms 38.75% KSPSolve_SYMMLQ 16ms 0.39% 0.8% 1120ms 27.30% AbstractCardiacCell::ComputeExceptVoltage 17ms 0.41% 1.22% 1003ms 24.45% AbstractOneStepIvpOdeSolver::InternalSolve 5ms 0.12% 1.34% 993ms 24.20% PCApply 0 0% 1.34% 986ms 24.03% PCApply_BJacobi_Singleblock 85ms 2.07% 3.41% 855ms 20.84% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.41% 853ms 20.79% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 3.44% 807ms 19.67% MatSolve 0 0% 3.44% 807ms 19.67% PCApply_ICC 794ms 19.35% 22.79% 799ms 19.47% MatSolve_SeqSBAIJ_1_NaturalOrdering 284ms 6.92% 29.71% 760ms 18.52% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 29.71% 724ms 17.65% KSP_PCApply.lto_priv.18 11ms 0.27% 29.98% 713ms 17.38% AbstractCardiacTissue::UpdateCaches (inline) 338ms 8.24% 38.22% 674ms 16.43% CellLuoRudy1991FromCellML::GetIIonic 3ms 0.073% 38.29% 649ms 15.82% MatMult 2ms 0.049% 38.34% 644ms 15.70% MatMult_SeqAIJ 639ms 15.57% 53.91% 644ms 15.70% MatMult_SeqAIJ_Inode (inline) 190ms 4.63% 58.54% 561ms 13.67% __GI___exp 34ms 0.83% 59.37% 369ms 8.99% BidomainSolver::SetupLinearSystem 0 0% 59.37% 337ms 8.21% KSPConvergedDefault 1ms 0.024% 59.40% 334ms 8.14% KSP_PCApply (partial-inline) 314ms 7.65% 67.05% 316ms 7.70% __ieee754_exp_fma 8ms 0.19% 67.24% 179ms 4.36% std::pow (inline) 11ms 0.27% 67.51% 167ms 4.07% __pow 11ms 0.27% 67.78% 153ms 3.73% __ieee754_pow_fma 0 0% 67.78% 122ms 2.97% MatNullSpaceRemove 118ms 2.88% 70.66% 118ms 2.88% [libchaste_heart.so] 73ms 1.78% 72.43% 116ms 2.83% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 72.43% 85ms 2.07% VecDuplicate 62ms 1.51% 73.95% 75ms 1.83% exp_inline (inline) 0 0% 73.95% 71ms 1.73% VecMDot 5ms 0.12% 74.07% 71ms 1.73% VecMXDot_Private 18ms 0.44% 74.51% 68ms 1.66% MPI_Wtime 67ms 1.63% 76.14% 67ms 1.63% log_inline (inline) 62ms 1.51% 77.65% 62ms 1.51% [libm.so.6] 53ms 1.29% 78.94% 54ms 1.32% VecMDot_Seq 6ms 0.15% 79.09% 53ms 1.29% PetscLogEventEndDefault 53ms 1.29% 80.38% 53ms 1.29% TimeStepper::CalculateNextTime 4ms 0.097% 80.48% 51ms 1.24% VecMAXPY 50ms 1.22% 81.70% 50ms 1.22% [[vdso]] 0 0% 81.70% 50ms 1.22% __GI___clock_gettime 50ms 1.22% 82.91% 50ms 1.22% ddot_ 6ms 0.15% 83.06% 48ms 1.17% VecCopy 11ms 0.27% 83.33% 47ms 1.15% TimeStepper::TimeStepper 2ms 0.049% 83.38% 45ms 1.10% VecDuplicate_MPI 6ms 0.15% 83.52% 44ms 1.07% PetscLogEventBeginDefault 1ms 0.024% 83.55% 40ms 0.97% VecDuplicate_Seq 6ms 0.15% 83.69% 39ms 0.95% PetscMallocA 1ms 0.024% 83.72% 38ms 0.93% KSP_RemoveNullSpace (inline) 1ms 0.024% 83.74% 38ms 0.93% VecDestroy 38ms 0.93% 84.67% 38ms 0.93% __memcpy_avx512_unaligned_erms 1ms 0.024% 84.69% 36ms 0.88% VecCreate 2ms 0.049% 84.74% 36ms 0.88% VecDot 7ms 0.17% 84.91% 35ms 0.85% TimeStepper::AdvanceOneTimeStep 1ms 0.024% 84.94% 34ms 0.83% VecNorm 0 0% 84.94% 33ms 0.8% CxxTest::RealWorldDescription::setUp 0 0% 84.94% 33ms 0.8% PMPI_Init_thread 0 0% 84.94% 33ms 0.8% PetscInitialize 1ms 0.024% 84.96% 33ms 0.8% PetscMallocAlign 0 0% 84.96% 33ms 0.8% PetscSetup::setUpWorld 0 0% 84.96% 33ms 0.8% PetscSetupUtils::CommonSetup 0 0% 84.96% 33ms 0.8% PetscSetupUtils::InitialisePetsc 0 0% 84.96% 33ms 0.8% ompi_mpi_init 15ms 0.37% 85.33% 32ms 0.78% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 31ms 0.76% 86.08% 32ms 0.78% VecMAXPY_Seq 0 0% 86.08% 31ms 0.76% VecDot_Seq 1ms 0.024% 86.11% 31ms 0.76% VecXDot_Seq_Private.constprop.0 31ms 0.76% 86.86% 31ms 0.76% [libchaste_ode.so] 1ms 0.024% 86.89% 29ms 0.71% VecCreate_MPI_Private 8ms 0.19% 87.08% 29ms 0.71% __GI___libc_malloc 5ms 0.12% 87.20% 28ms 0.68% VecAXPY 1ms 0.024% 87.23% 28ms 0.68% VecNorm_Seq 1ms 0.024% 87.25% 27ms 0.66% 26ms 0.63% 87.89% 27ms 0.66% AbstractOneStepIvpOdeSolver::Solve 2ms 0.049% 87.94% 27ms 0.66% __posix_memalign 0 0% 87.94% 25ms 0.61% PetscMemcpy (inline) 3ms 0.073% 88.01% 23ms 0.56% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 88.01% 23ms 0.56% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 88.01% 23ms 0.56% orte_init 1ms 0.024% 88.03% 22ms 0.54% VecCopy_Seq (partial-inline) 9ms 0.22% 88.25% 22ms 0.54% std::__cxx11::basic_string::basic_string 3ms 0.073% 88.33% 21ms 0.51% PetscOptionsFindPair