File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3687ms, 85.76% of 4299ms total Dropped 458 nodes (cum <= 21.50ms) flat flat% sum% cum cum% 0 0% 0% 4290ms 99.79% __libc_start_call_main 0 0% 0% 4290ms 99.79% __libc_start_main_impl 0 0% 0% 4290ms 99.79% _start 0 0% 0% 4287ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4287ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4287ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4287ms 99.72% main 0 0% 0% 4247ms 98.79% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4247ms 98.79% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4247ms 98.79% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4241ms 98.65% AbstractCardiacProblem::Solve 2ms 0.047% 0.047% 4230ms 98.39% AbstractDynamicLinearPdeSolver::Solve 16ms 0.37% 0.42% 2389ms 55.57% AbstractCardiacTissue::SolveCellSystems 0 0% 0.42% 1507ms 35.05% LinearSystem::Solve 0 0% 0.42% 1446ms 33.64% KSPSolve 3ms 0.07% 0.49% 1446ms 33.64% KSPSolve_Private 0 0% 0.49% 1408ms 32.75% KSPSolve_SYMMLQ 35ms 0.81% 1.30% 1357ms 31.57% AbstractCardiacCell::ComputeExceptVoltage 26ms 0.6% 1.91% 1152ms 26.80% AbstractOneStepIvpOdeSolver::InternalSolve 50ms 1.16% 3.07% 973ms 22.63% EulerIvpOdeSolver::CalculateNextYValue 265ms 6.16% 9.23% 905ms 21.05% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.023% 9.26% 895ms 20.82% PCApply 1ms 0.023% 9.28% 887ms 20.63% PCApply_BJacobi_Singleblock 380ms 8.84% 18.12% 828ms 19.26% __GI___exp 24ms 0.56% 18.68% 818ms 19.03% AbstractCardiacTissue::UpdateCaches (inline) 271ms 6.30% 24.98% 761ms 17.70% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.023% 25.01% 735ms 17.10% KSPSolve_PREONLY (partial-inline) 5ms 0.12% 25.12% 687ms 15.98% MatSolve 0 0% 25.12% 687ms 15.98% PCApply_ICC 667ms 15.52% 40.64% 670ms 15.59% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.64% 667ms 15.52% KSP_PCApply.lto_priv.18 401ms 9.33% 49.97% 431ms 10.03% __ieee754_exp_fma 3ms 0.07% 50.03% 405ms 9.42% MatMult 6ms 0.14% 50.17% 401ms 9.33% MatMult_SeqAIJ (partial-inline) 390ms 9.07% 59.25% 400ms 9.30% MatMult_SeqAIJ_Inode (inline) 0 0% 59.25% 303ms 7.05% KSPConvergedDefault 0 0% 59.25% 300ms 6.98% KSP_PCApply (partial-inline) 63ms 1.47% 60.71% 283ms 6.58% BidomainSolver::SetupLinearSystem 3ms 0.07% 60.78% 242ms 5.63% std::pow (inline) 22ms 0.51% 61.29% 239ms 5.56% __pow 27ms 0.63% 61.92% 217ms 5.05% __ieee754_pow_fma 66ms 1.54% 63.46% 163ms 3.79% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 63.50% 116ms 2.70% MatNullSpaceRemove 107ms 2.49% 65.99% 107ms 2.49% log_inline (inline) 1ms 0.023% 66.02% 99ms 2.30% VecDuplicate 22ms 0.51% 66.53% 79ms 1.84% MPI_Wtime 79ms 1.84% 68.36% 79ms 1.84% exp_inline (inline) 0 0% 68.36% 75ms 1.74% VecMDot 5ms 0.12% 68.48% 75ms 1.74% VecMXDot_Private 10ms 0.23% 68.71% 68ms 1.58% VecCopy 14ms 0.33% 69.04% 66ms 1.54% PetscLogEventEndDefault 3ms 0.07% 69.11% 62ms 1.44% VecDuplicate_MPI 14ms 0.33% 69.43% 60ms 1.40% PetscLogEventBeginDefault 35ms 0.81% 70.25% 57ms 1.33% TimeStepper::TimeStepper 2ms 0.047% 70.30% 57ms 1.33% __GI___clock_gettime 55ms 1.28% 71.57% 55ms 1.28% [[vdso]] 43ms 1.00% 72.58% 52ms 1.21% AbstractOneStepIvpOdeSolver::Solve 52ms 1.21% 73.78% 52ms 1.21% VecMDot_Seq 50ms 1.16% 74.95% 50ms 1.16% ddot_ 2ms 0.047% 74.99% 48ms 1.12% VecNorm 48ms 1.12% 76.11% 48ms 1.12% __memcpy_avx_unaligned_erms 14ms 0.33% 76.44% 47ms 1.09% AbstractCardiacCell::SetVoltage (partial-inline) 3ms 0.07% 76.51% 47ms 1.09% VecAXPY 5ms 0.12% 76.62% 44ms 1.02% VecCreate 0 0% 76.62% 44ms 1.02% VecDestroy 0 0% 76.62% 43ms 1.00% AbstractBidomainSolver::FinaliseLinearSystem 19ms 0.44% 77.06% 42ms 0.98% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 77.06% 42ms 0.98% PetscMemcpy (inline) 10ms 0.23% 77.30% 41ms 0.95% PetscMallocA 22ms 0.51% 77.81% 39ms 0.91% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 31ms 0.72% 78.53% 39ms 0.91% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.07% 78.60% 39ms 0.91% VecMAXPY 38ms 0.88% 79.48% 39ms 0.91% __ieee754_log_fma 1ms 0.023% 79.51% 38ms 0.88% VecCopy_Seq (partial-inline) 0 0% 79.51% 37ms 0.86% VecDot 13ms 0.3% 79.81% 37ms 0.86% std::__cxx11::basic_string::basic_string 0 0% 79.81% 36ms 0.84% PetscTimeSubtract (inline) 1ms 0.023% 79.83% 36ms 0.84% VecDuplicate_Seq 36ms 0.84% 80.67% 36ms 0.84% std::isinf (inline) 0 0% 80.67% 35ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 80.67% 35ms 0.81% PMPI_Init_thread 0 0% 80.67% 35ms 0.81% PetscInitialize 0 0% 80.67% 35ms 0.81% PetscSetup::setUpWorld 0 0% 80.67% 35ms 0.81% PetscSetupUtils::CommonSetup 0 0% 80.67% 35ms 0.81% PetscSetupUtils::InitialisePetsc 0 0% 80.67% 35ms 0.81% VecDot_Seq 0 0% 80.67% 35ms 0.81% VecNorm_Seq 0 0% 80.67% 35ms 0.81% VecXDot_Seq_Private.constprop.0 0 0% 80.67% 35ms 0.81% ompi_mpi_init 34ms 0.79% 81.46% 34ms 0.79% TimeStepper::CalculateNextTime 16ms 0.37% 81.83% 34ms 0.79% top12 (inline) 3ms 0.07% 81.90% 32ms 0.74% VecCreate_MPI_Private 1ms 0.023% 81.93% 31ms 0.72% PetscMallocAlign 16ms 0.37% 82.30% 31ms 0.72% TimeStepper::AdvanceOneTimeStep 1ms 0.023% 82.32% 30ms 0.7% 0 0% 82.32% 30ms 0.7% VecAXPY_Seq 0 0% 82.32% 28ms 0.65% VecNorm_MPI 2ms 0.047% 82.37% 28ms 0.65% VecNorm_MPI_Default.constprop.0 24ms 0.56% 82.93% 26ms 0.6% VecMAXPY_Seq 26ms 0.6% 83.53% 26ms 0.6% [libchaste_heart.so] 26ms 0.6% 84.14% 26ms 0.6% daxpy_ 19ms 0.44% 84.58% 24ms 0.56% AbstractParameterisedSystem::SetAnyVariable 23ms 0.54% 85.11% 23ms 0.54% RegularStimulus::GetStimulus 0 0% 85.11% 23ms 0.54% orte_init 22ms 0.51% 85.62% 23ms 0.54% std::vector::operator[] (inline) 6ms 0.14% 85.76% 22ms 0.51% VecScale