Sample MOOCHO Algorithm Summary Output (MoochoSummary.out)
[Sample MOOCHO input and output.]

Collaboration diagram for Sample MOOCHO Algorithm Summary Output (MoochoSummary.out):

Below is the output file MoochoSummary.out from the program ExampleNLPBanded.exe using the command-line arguments

--echo-command-line --nD=3000 --bw=10 --diag-scal=1e+3 --nI=5 --xIl=1e-5 --xo=0.1

given the Moocho.opt options file shown here.

Here is the other types of output that is associated with this run:

Output file MoochoSummary.out:

********************************************************************
*** Algorithm iteration summary output                           ***
***                                                              ***
*** Below, a summary table of the SQP iterations is given as     ***
*** well as a table of the CPU times for each step (if the       ***
*** option MoochoSolver::algo_timing = true is set).             ***
********************************************************************

*** Echoing input options ...

begin_options

options_group DecompositionSystemStateStepBuilderStd {
    null_space_matrix = EXPLICIT;
    range_space_matrix = ORTHOGONAL;
}

options_group NLPAlgoConfigMamaJama {
    line_search_method = FILTER;
    quasi_newton = BFGS;
}

options_group NLPSolverClientInterface {
    calc_conditioning = true;
    calc_matrix_info_null_space_only = true;
    calc_matrix_norms = true;
    feas_tol = 1e-7;
    journal_output_level = PRINT_ALGORITHM_STEPS;
    journal_print_digits = 10;
    max_iter = 20;
    max_run_time = 2.0;
    null_space_journal_output_level = PRINT_ITERATION_QUANTITIES;
    opt_tol = 1e-2;
}

end_options


Allocating workspace_MB = 0.2404 megabytes of temporary workspace for automatic arrays only ...

*** Setting up to run MOOCHO on the NLP using a configuration object of type 'MoochoPack::NLPAlgoConfigMamaJama' ...

test_nlp = true: Testing the NLP! ...

Testing the supported NLPFirstOrder interface ...

Successful end of testing of the nlp


********************************
*** Start of rSQP Iterations ***
n = 3005, m = 3000, nz = 59910

 k      f              ||Gf||inf      ||c||inf       ||rGL||inf     quasi-Newton #act   #adds  #drops #qpitr wcfi     ||Ypy||2       ||Zpz||2       ||d||inf       alpha
 ----   ------------   ------------   ------------   ------------   ------------ ------ ------ ------ ------ ----   ------------   ------------   ------------   ------------
    0         15.025            0.1        119.973        10.8096    initialized      0      -      -      0  c f        1.19996        110.323         10.286         0.0625
    1        2.18207       0.742874        69.6825       0.374625        updated      0      -      -      0  c f        1.06215      0.0603318       0.281631              1
    2       0.615764       0.461355          15.83       0.415546         skiped      0      -      -      0  c f        0.39705      0.0578495      0.0682893              1
    3       0.386953       0.393147        1.34453       0.392728         skiped      0      -      -      0  c f      0.0382224      0.0530505      0.0241417              1
    4       0.340244       0.369005      0.0449874       0.369005   damp.updated      0      -      -      0  c f     0.00132495       0.249154       0.111452              1
    5        0.16575       0.257554     0.00617911       0.257554        updated      1      -      -      1  c f    0.000215694       0.575732       0.257536              1
    6    4.30785e-09    1.72885e-05     0.00160011    2.42272e-05        updated      4      -      -      4  c f    8.83285e-05    3.94154e-06    2.50836e-06              1
 ----   ------------   ------------   ------------   ------------   ------------ ------ ------ ------ ------ ----
    7    3.09226e-10    1.47801e-05    7.94737e-09    1.47801e-05        updated      4      -      -      4  c f     3.1178e-10    1.55104e-06    1.55104e-06

Number of function evaluations:
-------------------------------
f(x)  : 18
c(x)  : 18
Gf(x) : 9
Gc(x) : 9


************************
**** Solution Found ****

  total time = 0.809928 sec.


**************************************
*** Algorithm step CPU times (sec) ***

Step names
----------
1) "EvalNewPoint"
2) "QuasiNormalStep"
3) "ReducedGradient"
4) "ReducedHessian"
5) "TangentialStep"
6) "CalcDFromYPYZPZ"
7) "CalcReducedGradLagrangian"
8) "CheckConvergence"
9) "LineSearch"
10) Iteration total

            steps 1...10 ->

    iter k         1         2         3         4         5         6         7         8         9        10
  --------  --------  --------  --------  --------  --------  --------  --------  --------  --------  --------
         0    0.1131   0.01118  0.000376  0.000393  0.002382  0.000331     6e-05   0.00034    0.0173    0.1455
         1    0.0731    0.0109  0.000355  0.001066  0.001893  0.000287   4.6e-05  0.000335  0.003622   0.09161
         2   0.07214   0.01101  0.000369  0.000106  0.001899  0.000287   4.6e-05  0.000339  0.003606    0.0898
         3   0.07221   0.01095  0.000368  0.000105  0.001924  0.000286   4.8e-05  0.000307  0.003624   0.08983
         4   0.07225   0.01108  0.000371  0.000889  0.001905  0.000289   4.6e-05  0.000309  0.003604   0.09075
         5   0.07241   0.01103  0.000376    0.0008  0.005433  0.000286  0.000496  0.000308  0.003635   0.09477
         6   0.07271   0.01119   0.00038  0.000809   0.01511  0.000294  0.000496  0.000329  0.003605    0.1049
         7    0.0721   0.01133  0.000423  0.000899   0.01529  0.000286  0.000545  0.000307         0    0.1012
  --------  --------  --------  --------  --------  --------  --------  --------  --------  --------  --------
total(sec)      0.62   0.08868  0.003018  0.005067   0.04583  0.002346  0.001783  0.002574     0.039    0.8083
 av(sec)/k    0.0775   0.01109 0.0003772 0.0006334  0.005729 0.0002932 0.0002229 0.0003217  0.004875     0.101
  min(sec)    0.0721    0.0109  0.000355  0.000105  0.001893  0.000286   4.6e-05  0.000307         0    0.0898
  max(sec)    0.1131   0.01133  0.000423  0.001066   0.01529  0.000331  0.000545   0.00034    0.0173    0.1455
   % total     76.71     10.97    0.3734    0.6268      5.67    0.2902    0.2206    0.3184     4.824       100
------------------------------
total CPU time = 0.8083 sec


================================================================================

                              TimeMonitor Results

Timer Name    Local time (num calls)    
================================================================================

*** Statistics for automatic array workspace:
Number of megabytes of preallocated workspace                = 0.2404
Number of allocations using preallocated workspace           = 3960
Number of dynamic allocations beyond preallocated workspace  = 1

Generated on Wed May 12 21:57:50 2010 for MOOCHO by  doxygen 1.4.7