File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3579ms, 84.83% of 4219ms total Dropped 467 nodes (cum <= 21.09ms) flat flat% sum% cum cum% 0 0% 0% 4210ms 99.79% __libc_start_call_main 0 0% 0% 4210ms 99.79% __libc_start_main_impl 0 0% 0% 4210ms 99.79% _start 0 0% 0% 4207ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4207ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4207ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4207ms 99.72% main 0 0% 0% 4166ms 98.74% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4166ms 98.74% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4166ms 98.74% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4157ms 98.53% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4133ms 97.96% AbstractDynamicLinearPdeSolver::Solve 20ms 0.47% 0.5% 2302ms 54.56% AbstractCardiacTissue::SolveCellSystems 2ms 0.047% 0.55% 1351ms 32.02% LinearSystem::Solve 1ms 0.024% 0.57% 1295ms 30.69% KSPSolve 5ms 0.12% 0.69% 1294ms 30.67% KSPSolve_Private 28ms 0.66% 1.35% 1270ms 30.10% AbstractCardiacCell::ComputeExceptVoltage 4ms 0.095% 1.45% 1247ms 29.56% KSPSolve_SYMMLQ 25ms 0.59% 2.04% 1096ms 25.98% AbstractOneStepIvpOdeSolver::InternalSolve 65ms 1.54% 3.58% 919ms 21.78% EulerIvpOdeSolver::CalculateNextYValue 228ms 5.40% 8.98% 843ms 19.98% CellLuoRudy1991FromCellML::EvaluateYDerivatives 23ms 0.55% 9.53% 834ms 19.77% AbstractCardiacTissue::UpdateCaches (inline) 288ms 6.83% 16.35% 768ms 18.20% CellLuoRudy1991FromCellML::GetIIonic 369ms 8.75% 25.10% 761ms 18.04% __GI___exp 3ms 0.071% 25.17% 697ms 16.52% PCApply 1ms 0.024% 25.20% 687ms 16.28% PCApply_BJacobi_Singleblock 0 0% 25.20% 556ms 13.18% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 25.22% 525ms 12.44% KSP_PCApply.lto_priv.18 1ms 0.024% 25.24% 514ms 12.18% PCApply_ICC 4ms 0.095% 25.34% 513ms 12.16% MatSolve 501ms 11.87% 37.21% 508ms 12.04% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 5ms 0.12% 37.33% 464ms 11.00% MatMult 10ms 0.24% 37.57% 452ms 10.71% MatMult_SeqAIJ (partial-inline) 441ms 10.45% 48.02% 450ms 10.67% MatMult_SeqAIJ_Inode (inline) 102ms 2.42% 50.44% 425ms 10.07% BidomainSolver::SetupLinearSystem 308ms 7.30% 57.74% 365ms 8.65% __ieee754_exp_fma 0 0% 57.74% 268ms 6.35% KSPConvergedDefault 18ms 0.43% 58.17% 254ms 6.02% __pow 0 0% 58.17% 254ms 6.02% std::pow (inline) 1ms 0.024% 58.19% 246ms 5.83% KSP_PCApply (partial-inline) 18ms 0.43% 58.62% 236ms 5.59% __ieee754_pow_fma 64ms 1.52% 60.13% 117ms 2.77% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 60.13% 115ms 2.73% MatNullSpaceRemove 107ms 2.54% 62.67% 107ms 2.54% log_inline (inline) 95ms 2.25% 64.92% 98ms 2.32% exp_inline (inline) 1ms 0.024% 64.94% 96ms 2.28% VecDuplicate 28ms 0.66% 65.61% 81ms 1.92% MPI_Wtime 1ms 0.024% 65.63% 75ms 1.78% VecMDot 2ms 0.047% 65.68% 74ms 1.75% VecMXDot_Private 73ms 1.73% 67.41% 73ms 1.73% ddot_ 15ms 0.36% 67.76% 67ms 1.59% PetscLogEventEndDefault 25ms 0.59% 68.36% 64ms 1.52% TimeStepper::TimeStepper 16ms 0.38% 68.74% 60ms 1.42% AbstractCardiacCell::SetVoltage (partial-inline) 58ms 1.37% 70.11% 58ms 1.37% [libchaste_heart.so] 1ms 0.024% 70.14% 57ms 1.35% VecDuplicate_MPI 41ms 0.97% 71.11% 54ms 1.28% top12 (inline) 51ms 1.21% 72.32% 53ms 1.26% VecMDot_Seq 53ms 1.26% 73.57% 53ms 1.26% [[vdso]] 0 0% 73.57% 53ms 1.26% __GI___clock_gettime 9ms 0.21% 73.79% 52ms 1.23% PetscLogEventBeginDefault 0 0% 73.79% 52ms 1.23% VecDot 1ms 0.024% 73.81% 52ms 1.23% VecNorm 8ms 0.19% 74.00% 51ms 1.21% VecCopy 1ms 0.024% 74.02% 48ms 1.14% KSP_RemoveNullSpace (inline) 5ms 0.12% 74.14% 46ms 1.09% VecCreate 1ms 0.024% 74.16% 46ms 1.09% VecDestroy 11ms 0.26% 74.43% 45ms 1.07% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 74.43% 45ms 1.07% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 74.43% 45ms 1.07% VecDot_Seq 0 0% 74.43% 45ms 1.07% VecXDot_Seq_Private.constprop.0 31ms 0.73% 75.16% 44ms 1.04% AbstractOneStepIvpOdeSolver::Solve 5ms 0.12% 75.28% 43ms 1.02% PetscMallocA 33ms 0.78% 76.06% 41ms 0.97% AbstractParameterisedSystem::SetAnyVariable 4ms 0.095% 76.16% 40ms 0.95% VecAXPY 3ms 0.071% 76.23% 40ms 0.95% VecMAXPY 40ms 0.95% 77.17% 40ms 0.95% __ieee754_log_fma 39ms 0.92% 78.10% 39ms 0.92% TimeStepper::CalculateNextTime 0 0% 78.10% 39ms 0.92% VecNorm_Seq 38ms 0.9% 79.00% 38ms 0.9% DistributedVector::Stripe::operator[] (inline) 4ms 0.095% 79.09% 38ms 0.9% PetscMallocAlign 2ms 0.047% 79.14% 38ms 0.9% VecDuplicate_Seq 38ms 0.9% 80.04% 38ms 0.9% __memcpy_avx_unaligned_erms 0 0% 80.04% 37ms 0.88% CxxTest::RealWorldDescription::setUp 0 0% 80.04% 37ms 0.88% PMPI_Init_thread 0 0% 80.04% 37ms 0.88% PetscInitialize 0 0% 80.04% 37ms 0.88% PetscSetup::setUpWorld 0 0% 80.04% 37ms 0.88% PetscSetupUtils::CommonSetup 0 0% 80.04% 37ms 0.88% PetscSetupUtils::InitialisePetsc 0 0% 80.04% 37ms 0.88% ompi_mpi_init 1ms 0.024% 80.07% 34ms 0.81% PetscTimeSubtract (inline) 32ms 0.76% 80.82% 32ms 0.76% DistributedVector::Iterator::operator!= 0 0% 80.82% 32ms 0.76% PetscMemcpy (inline) 32ms 0.76% 81.58% 32ms 0.76% asuint64 (inline) 4ms 0.095% 81.68% 30ms 0.71% VecCreate_MPI_Private 0 0% 81.68% 29ms 0.69% 24ms 0.57% 82.25% 28ms 0.66% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 23ms 0.55% 82.79% 28ms 0.66% AbstractParameterisedSystem::GetAnyVariable 27ms 0.64% 83.43% 27ms 0.64% [libm.so.6] 3ms 0.071% 83.50% 27ms 0.64% __GI___libc_malloc 19ms 0.45% 83.95% 26ms 0.62% TimeStepper::AdvanceOneTimeStep 0 0% 83.95% 26ms 0.62% VecNorm_MPI 0 0% 83.95% 26ms 0.62% VecNorm_MPI_Default.constprop.0 2ms 0.047% 84.00% 26ms 0.62% __posix_memalign 0 0% 84.00% 24ms 0.57% VecCopy_Seq (partial-inline) 0 0% 84.00% 24ms 0.57% orte_init 6ms 0.14% 84.14% 23ms 0.55% AbstractCardiacCell::GetVoltage 0 0% 84.14% 23ms 0.55% AbstractFeVolumeIntegralAssembler::DoAssemble 1ms 0.024% 84.17% 23ms 0.55% VecAXPY_Seq 21ms 0.5% 84.66% 23ms 0.55% VecMAXPY_Seq 5ms 0.12% 84.78% 22ms 0.52% PetscOptionsFindPair 1ms 0.024% 84.81% 22ms 0.52% VecScale 1ms 0.024% 84.83% 22ms 0.52% VecSet