File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3742ms, 87.96% of 4254ms total Dropped 457 nodes (cum <= 21.27ms) flat flat% sum% cum cum% 0 0% 0% 4247ms 99.84% __libc_start_call_main 0 0% 0% 4247ms 99.84% __libc_start_main_impl 0 0% 0% 4247ms 99.84% _start 0 0% 0% 4244ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4244ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4244ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4244ms 99.76% main 0 0% 0% 4206ms 98.87% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4206ms 98.87% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4206ms 98.87% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 2ms 0.047% 0.047% 4200ms 98.73% AbstractCardiacProblem::Solve 1ms 0.024% 0.071% 4188ms 98.45% AbstractDynamicLinearPdeSolver::Solve 13ms 0.31% 0.38% 2474ms 58.16% AbstractCardiacTissue::SolveCellSystems 55ms 1.29% 1.67% 1472ms 34.60% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.67% 1426ms 33.52% LinearSystem::Solve 0 0% 1.67% 1378ms 32.39% KSPSolve 5ms 0.12% 1.79% 1378ms 32.39% KSPSolve_Private 1ms 0.024% 1.81% 1328ms 31.22% KSPSolve_SYMMLQ 27ms 0.63% 2.44% 1231ms 28.94% AbstractOneStepIvpOdeSolver::InternalSolve 68ms 1.60% 4.04% 1034ms 24.31% EulerIvpOdeSolver::CalculateNextYValue 234ms 5.50% 9.54% 918ms 21.58% CellLuoRudy1991FromCellML::EvaluateYDerivatives 403ms 9.47% 19.02% 860ms 20.22% __GI___exp 0 0% 19.02% 807ms 18.97% PCApply 35ms 0.82% 19.84% 802ms 18.85% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 19.86% 801ms 18.83% PCApply_BJacobi_Singleblock 254ms 5.97% 25.83% 748ms 17.58% CellLuoRudy1991FromCellML::GetIIonic 0 0% 25.83% 666ms 15.66% KSPSolve_PREONLY (partial-inline) 4ms 0.094% 25.93% 630ms 14.81% MatSolve 0 0% 25.93% 630ms 14.81% PCApply_ICC 614ms 14.43% 40.36% 622ms 14.62% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 40.36% 617ms 14.50% KSP_PCApply.lto_priv.18 401ms 9.43% 49.79% 433ms 10.18% __ieee754_exp_fma 5ms 0.12% 49.91% 427ms 10.04% MatMult 4ms 0.094% 50.00% 419ms 9.85% MatMult_SeqAIJ 408ms 9.59% 59.59% 418ms 9.83% MatMult_SeqAIJ_Inode (inline) 1ms 0.024% 59.61% 269ms 6.32% KSPConvergedDefault 1ms 0.024% 59.64% 254ms 5.97% std::pow (inline) 29ms 0.68% 60.32% 253ms 5.95% __pow 47ms 1.10% 61.42% 246ms 5.78% BidomainSolver::SetupLinearSystem 0 0% 61.42% 244ms 5.74% KSP_PCApply (partial-inline) 27ms 0.63% 62.06% 224ms 5.27% __ieee754_pow_fma 75ms 1.76% 63.82% 148ms 3.48% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 63.85% 107ms 2.52% MatNullSpaceRemove 106ms 2.49% 66.34% 106ms 2.49% log_inline (inline) 1ms 0.024% 66.36% 99ms 2.33% VecDuplicate 80ms 1.88% 68.24% 81ms 1.90% exp_inline (inline) 20ms 0.47% 68.71% 70ms 1.65% MPI_Wtime 0 0% 68.71% 68ms 1.60% VecMDot 5ms 0.12% 68.83% 68ms 1.60% VecMXDot_Private 50ms 1.18% 70.00% 66ms 1.55% TimeStepper::TimeStepper 6ms 0.14% 70.15% 54ms 1.27% PetscLogEventBeginDefault 3ms 0.071% 70.22% 54ms 1.27% VecDuplicate_MPI 6ms 0.14% 70.36% 53ms 1.25% VecCopy 7ms 0.16% 70.52% 51ms 1.20% AbstractCardiacCell::SetVoltage (partial-inline) 2ms 0.047% 70.57% 50ms 1.18% VecDestroy 50ms 1.18% 71.74% 50ms 1.18% [[vdso]] 0 0% 71.74% 50ms 1.18% __GI___clock_gettime 28ms 0.66% 72.40% 49ms 1.15% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 48ms 1.13% 73.53% 48ms 1.13% __memcpy_avx_unaligned_erms 10ms 0.24% 73.77% 45ms 1.06% PetscLogEventEndDefault 45ms 1.06% 74.82% 45ms 1.06% VecMDot_Seq 39ms 0.92% 75.74% 44ms 1.03% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 10ms 0.24% 75.98% 44ms 1.03% PetscMallocA 1ms 0.024% 76.00% 44ms 1.03% VecDuplicate_Seq 44ms 1.03% 77.03% 44ms 1.03% ddot_ 20ms 0.47% 77.50% 43ms 1.01% TimeStepper::AdvanceOneTimeStep 29ms 0.68% 78.19% 42ms 0.99% AbstractOneStepIvpOdeSolver::Solve 41ms 0.96% 79.15% 41ms 0.96% __ieee754_log_fma 12ms 0.28% 79.43% 41ms 0.96% top12 (inline) 39ms 0.92% 80.35% 39ms 0.92% non-virtual thunk to CellLuoRudy1991FromCellML::EvaluateYDerivatives(double, std::vector const&, std::vector&) 3ms 0.071% 80.42% 38ms 0.89% VecMAXPY 38ms 0.89% 81.31% 38ms 0.89% [libchaste_heart.so] 0 0% 81.31% 37ms 0.87% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 81.31% 37ms 0.87% PetscMemcpy (inline) 2ms 0.047% 81.36% 37ms 0.87% VecAXPY 1ms 0.024% 81.38% 37ms 0.87% VecCreate 10ms 0.24% 81.62% 36ms 0.85% AbstractBidomainSolver::CheckCompatibilityCondition 1ms 0.024% 81.64% 36ms 0.85% VecNorm 0 0% 81.64% 34ms 0.8% CxxTest::RealWorldDescription::setUp 0 0% 81.64% 34ms 0.8% PetscInitialize 1ms 0.024% 81.66% 34ms 0.8% PetscMallocAlign 0 0% 81.66% 34ms 0.8% PetscSetup::setUpWorld 0 0% 81.66% 34ms 0.8% PetscSetupUtils::CommonSetup 0 0% 81.66% 34ms 0.8% PetscSetupUtils::InitialisePetsc 0 0% 81.66% 34ms 0.8% VecDot 0 0% 81.66% 33ms 0.78% PMPI_Init_thread 1ms 0.024% 81.69% 33ms 0.78% VecCreate_MPI_Private 0 0% 81.69% 33ms 0.78% ompi_mpi_init 30ms 0.71% 82.39% 32ms 0.75% AbstractParameterisedSystem::SetAnyVariable 32ms 0.75% 83.15% 32ms 0.75% TimeStepper::CalculateNextTime 31ms 0.73% 83.87% 31ms 0.73% asuint64 (inline) 27ms 0.63% 84.51% 29ms 0.68% AbstractParameterisedSystem::GetAnyVariable 0 0% 84.51% 29ms 0.68% VecNorm_Seq 10ms 0.24% 84.74% 28ms 0.66% std::__cxx11::basic_string::basic_string 0 0% 84.74% 27ms 0.63% 0 0% 84.74% 27ms 0.63% VecDot_Seq 0 0% 84.74% 27ms 0.63% VecXDot_Seq_Private.constprop.0 27ms 0.63% 85.38% 27ms 0.63% std::isinf (inline) 0 0% 85.38% 26ms 0.61% VecCopy_Seq (partial-inline) 1ms 0.024% 85.40% 25ms 0.59% VecAXPY_Seq 0 0% 85.40% 25ms 0.59% VecNorm_MPI 1ms 0.024% 85.43% 25ms 0.59% VecNorm_MPI_Default.constprop.0 5ms 0.12% 85.54% 25ms 0.59% std::vector::_M_assign_aux (inline) 0 0% 85.54% 25ms 0.59% std::vector::assign (inline) 10ms 0.24% 85.78% 24ms 0.56% AbstractCardiacCell::GetVoltage (partial-inline) 24ms 0.56% 86.34% 24ms 0.56% DistributedVector::Iterator::operator!= 24ms 0.56% 86.91% 24ms 0.56% [libm.so.6] 0 0% 86.91% 24ms 0.56% orte_init 0 0% 86.91% 23ms 0.54% VecSetType 23ms 0.54% 87.45% 23ms 0.54% daxpy_ 22ms 0.52% 87.96% 22ms 0.52% VecMAXPY_Seq