Set parameter TokenServer to value "gurobi.cc.ic.ac.uk"

********************************
*** COLUMN GENERATION SCHEME ***
********************************

*** OUTER ITERATION 0 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 6.52844 seconds.
    local lower bound updated to 0.228659
    local upper bound updated to 0.428603

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.01442 seconds.
    local lower bound updated to 0.400319
    local upper bound updated to 0.400319
The Primal step takes 9.54563 (S)
lower bound updated to 0.400319

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.423849
    local lower bound updated to 0.422646

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.423849
    local lower bound updated to 0.423282

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.423849
    local lower bound updated to 0.423777

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.423846
    local lower bound updated to 0.423794

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.423794
    local lower bound updated to 0.423794
The Dual step takes 0.448364 (S)
upper bound updated to 0.423794
*** OUTER ITERATION 1 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 26.4682 seconds.
    local lower bound updated to 0.319018
    local upper bound updated to 0.421845

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.69803 seconds.
    local lower bound updated to 0.319157
    local upper bound updated to 0.415483

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 58.7722 seconds.
    local lower bound updated to 0.402055
    local upper bound updated to 0.411274

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 27.9088 seconds.
    local lower bound updated to 0.402055
    local upper bound updated to 0.408347

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 37.0835 seconds.
    local lower bound updated to 0.404658
    local upper bound updated to 0.408219

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 39.6939 seconds.
    local lower bound updated to 0.405745
    local upper bound updated to 0.407348

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 36.3299 seconds.
    local lower bound updated to 0.407348
    local upper bound updated to 0.407348
The Primal step takes 226.975 (S)
lower bound updated to 0.407348

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.425892
    local lower bound updated to 0.411604

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.41895
    local lower bound updated to 0.415169

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.418245
    local lower bound updated to 0.415314

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.415314
    local lower bound updated to 0.415314
The Dual step takes 1.78097 (S)
upper bound updated to 0.415314
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 96.4433 seconds.
    local lower bound updated to 0.34604
    local upper bound updated to 0.414915

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 25.3549 seconds.
    local lower bound updated to 0.387431
    local upper bound updated to 0.411799

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 94.1284 seconds.
    local lower bound updated to 0.403179
    local upper bound updated to 0.411579

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 116.09 seconds.
    local lower bound updated to 0.407211
    local upper bound updated to 0.410448

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 73.3041 seconds.
    local lower bound updated to 0.407211
    local upper bound updated to 0.409233

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 104.739 seconds.
    local lower bound updated to 0.407447
    local upper bound updated to 0.408811

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 130.35 seconds.
    local lower bound updated to 0.408509
    local upper bound updated to 0.408791

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 131.268 seconds.
    local lower bound updated to 0.408711
    local upper bound updated to 0.408786

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 146.174 seconds.
    local lower bound updated to 0.408786
    local upper bound updated to 0.408786
The Primal step takes 917.894 (S)
lower bound updated to 0.408786

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.42277
    local lower bound updated to 0.408786

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.421757
    local lower bound updated to 0.410947

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.411728
    local lower bound updated to 0.411052

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.411052
    local lower bound updated to 0.411052
The Dual step takes 5.56268 (S)
upper bound updated to 0.411052
*** OUTER ITERATION 3 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.024 seconds.
    local lower bound updated to 0.356635
    local upper bound updated to 0.411052

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.029 seconds.
    local lower bound updated to 0.408916
    local upper bound updated to 0.410928

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 200.02 seconds.
    local lower bound updated to 0.408916
    local upper bound updated to 0.410703

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 200.022 seconds.
    local lower bound updated to 0.409586
    local upper bound updated to 0.410426

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 200.025 seconds.
    local lower bound updated to 0.409586
    local upper bound updated to 0.410406

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 200.035 seconds.
    local lower bound updated to 0.409802
    local upper bound updated to 0.410341

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 200.039 seconds.
    local lower bound updated to 0.410016
    local upper bound updated to 0.41024

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 200.025 seconds.
    local lower bound updated to 0.410121
    local upper bound updated to 0.410189

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 200.027 seconds.
    local lower bound updated to 0.410121
    local upper bound updated to 0.410185

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 200.029 seconds.
    local lower bound updated to 0.410184
    local upper bound updated to 0.410185
The Primal step takes 2000.33 (S)
lower bound updated to 0.410184

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.424079
    local lower bound updated to 0.410185

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.416788
    local lower bound updated to 0.410202

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.410892
    local lower bound updated to 0.410218

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.410218
    local lower bound updated to 0.410218
The Dual step takes 12.4583 (S)
upper bound updated to 0.410218
*** OUTER ITERATION 4 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 139.928 seconds.
    local lower bound updated to 0.357082
    local upper bound updated to 0.410218

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.033 seconds.
    local lower bound updated to 0.410218
    local upper bound updated to 0.410218
The Primal step takes 339.974 (S)
lower bound updated to 0.410218

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.424054
    local lower bound updated to 0.410218

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.410218
    local lower bound updated to 0.410218
The Dual step takes 5.43435 (S)
upper bound updated to 0.410218
This is to get the final optimal randomized assortment

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 136.454 seconds.
    local lower bound updated to 0.357082
    local upper bound updated to 0.410218

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.031 seconds.
    local lower bound updated to 0.410218
    local upper bound updated to 0.410218
The time obtain the optimal randomized assortment is 3856.9 (S)
Now, the randomization optimization has been finished. We are checking the lower bound ....
Set parameter OutputFlag to value 1
Set parameter LogFile to value "/rds/general/user/zw1819/home/RAO/RAO_MC/FinalRun/RecFMResult/logFile_17_4_200.000000_204218.log"
The computation time limit for a lower bound is 82133
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 20 physical cores, 40 logical processors, using up to 4 threads
Optimize a model with 2604 rows, 1990 columns and 8547 nonzeros
Model fingerprint: 0x8a0f1e9c
Variable types: 1925 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [6e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 604 rows and 1088 columns
Presolve time: 0.02s
Presolved: 2000 rows, 902 columns, 7771 nonzeros
Variable types: 839 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.5078558
Found heuristic solution: objective 0.5009477

Root relaxation: objective 2.596216e-01, 227 iterations, 0.00 seconds (0.00 work units)

    Nodes    |    Current Node    |     Objective Bounds      |     Work
 Expl Unexpl |  Obj  Depth IntInf | Incumbent    BestBd   Gap | It/Node Time

     0     0    0.25962    0   20    0.50095    0.25962  48.2%     -    0s
H    0     0                       0.4828683    0.25962  46.2%     -    0s
H    0     0                       0.4818770    0.25962  46.1%     -    0s
H    0     0                       0.4433602    0.26045  41.3%     -    0s
     0     0    0.26045    0   24    0.44336    0.26045  41.3%     -    0s
H    0     0                       0.4323783    0.26045  39.8%     -    0s
H    0     0                       0.4311800    0.30538  29.2%     -    0s
     0     0    0.30538    0   31    0.43118    0.30538  29.2%     -    0s
H    0     0                       0.4165498    0.30538  26.7%     -    0s
H    0     0                       0.4114624    0.30538  25.8%     -    0s
     0     0    0.30538    0   31    0.41146    0.30538  25.8%     -    0s
H    0     0                       0.4105674    0.31132  24.2%     -    0s
     0     0    0.31132    0   34    0.41057    0.31132  24.2%     -    0s
     0     0    0.31255    0   35    0.41057    0.31255  23.9%     -    0s
     0     0    0.32542    0   33    0.41057    0.32542  20.7%     -    0s
     0     0    0.32717    0   35    0.41057    0.32717  20.3%     -    0s
     0     0    0.33033    0   32    0.41057    0.33033  19.5%     -    0s
H    0     0                       0.4102181    0.33033  19.5%     -    0s
     0     2    0.33033    0   32    0.41022    0.33033  19.5%     -    2s
   590   383    0.37878   12   14    0.41022    0.34815  15.1%  26.7    5s
  1394   643    0.37371   13   16    0.41022    0.35302  13.9%  23.1   10s
  2106   902    0.39117   13   32    0.41022    0.35558  13.3%  22.2   15s
  3092  1072    0.41010   29    6    0.41022    0.36018  12.2%  25.5   21s
  4181  1091     cutoff   31         0.41022    0.36568  10.9%  26.1   26s
  4463  1192    0.38585   25   16    0.41022    0.36585  10.8%  26.0   31s
  5284  1227    0.38592   23   22    0.41022    0.36811  10.3%  25.7   36s
  5635  1356     cutoff   31         0.41022    0.36869  10.1%  25.9   41s
  6302  1525     cutoff   25         0.41022    0.37003  9.80%  26.0   45s
  6626  1603     cutoff   29         0.41022    0.37056  9.67%  26.1   50s
  7369  1734    0.39149   27   16    0.41022    0.37136  9.47%  26.0   55s
  8459  1971     cutoff   26         0.41022    0.37294  9.09%  25.9   61s
  9357  2189 infeasible   32         0.41022    0.37361  8.92%  26.1   67s
  9773  2253    0.40360   31   14    0.41022    0.37398  8.83%  26.1   70s
 11650  2602    0.39667   29   14    0.41022    0.37589  8.37%  25.9   75s
 12607  2810    0.40770   31   12    0.41022    0.37687  8.13%  25.8   81s
 14218  2980     cutoff   31         0.41022    0.37798  7.86%  25.7   86s
 15423  3198    0.40642   28    8    0.41022    0.37859  7.71%  25.6   90s
 17207  3416    0.40404   29   12    0.41022    0.37976  7.42%  25.5   95s
 18777  3559     cutoff   32         0.41022    0.38065  7.21%  25.3  101s
 19700  3644    0.40791   27    8    0.41022    0.38099  7.13%  25.2  105s
 21019  3830    0.39186   25   15    0.41022    0.38161  6.97%  25.2  111s
 22775  4009    0.40275   30   14    0.41022    0.38227  6.81%  25.1  116s
 23907  4142     cutoff   31         0.41022    0.38258  6.74%  25.0  120s
 25235  4233     cutoff   29         0.41022    0.38313  6.60%  24.9  125s
 26570  4302    0.40200   28   15    0.41022    0.38371  6.46%  24.8  130s
 27152  4349     cutoff   34         0.41022    0.38394  6.41%  24.8  135s
H27494  4349                       0.4102181    0.38397  6.40%  24.8  135s
H27544  4398                       0.4102181    0.38409  6.37%  24.8  137s
 28418  4468     cutoff   32         0.41022    0.38445  6.28%  24.7  141s
H28500  4468                       0.4102181    0.38445  6.28%  24.7  141s
 29561  4516 infeasible   31         0.41022    0.38494  6.16%  24.6  145s
 31235  4581    0.40504   26   14    0.41022    0.38544  6.04%  24.5  151s
H31555  4581                       0.4102181    0.38545  6.04%  24.5  151s
 32740  4632    0.40268   29   17    0.41022    0.38588  5.93%  24.4  157s
 33452  4672     cutoff   31         0.41022    0.38612  5.87%  24.4  161s
 34689  4687    0.40739   30   12    0.41022    0.38648  5.79%  24.3  165s
 35319  4720 infeasible   27         0.41022    0.38671  5.73%  24.3  170s
 37753  4773    0.40036   30   15    0.41022    0.38739  5.57%  24.1  177s
 38893  4777     cutoff   33         0.41022    0.38767  5.50%  24.1  181s
 39826  4785    0.39322   25   16    0.41022    0.38791  5.44%  24.0  185s
 40992  4815    0.40224   28   16    0.41022    0.38820  5.37%  24.0  190s
 41864  4807    0.38954   27   16    0.41022    0.38843  5.31%  23.9  195s
 43357  4814    0.40597   29   10    0.41022    0.38886  5.21%  23.9  201s
 45004  4801    0.39825   28   14    0.41022    0.38936  5.08%  23.8  206s
 46356  4772    0.40108   31   12    0.41022    0.38970  5.00%  23.8  210s
 48094  4750     cutoff   35         0.41022    0.39018  4.89%  23.7  215s
 49729  4712     cutoff   32         0.41022    0.39056  4.79%  23.6  220s
 51065  4697    0.40363   30   16    0.41022    0.39102  4.68%  23.6  225s
 53253  4604     cutoff   31         0.41022    0.39159  4.54%  23.5  232s
 55311  4508    0.40788   28   12    0.41022    0.39224  4.38%  23.4  236s
 56112  4467    0.40919   27   19    0.41022    0.39250  4.32%  23.4  241s
H56804  4462                       0.4102181    0.39262  4.29%  23.4  243s
 57390  4419 infeasible   36         0.41022    0.39280  4.25%  23.4  247s
 58251  4348     cutoff   28         0.41022    0.39298  4.20%  23.4  251s
 59523  4260    0.40045   28   17    0.41022    0.39334  4.11%  23.3  255s
 61492  4136    0.39516   28   14    0.41022    0.39396  3.96%  23.3  260s
 62900  3964    0.40154   32   13    0.41022    0.39434  3.87%  23.3  265s
H64252  3884                       0.4102181    0.39483  3.75%  23.2  268s
H64255  3884                       0.4102181    0.39483  3.75%  23.2  268s
 64343  3800     cutoff   26         0.41022    0.39486  3.75%  23.2  270s
 66235  3613     cutoff   31         0.41022    0.39560  3.56%  23.2  275s
 68058  3283     cutoff   29         0.41022    0.39614  3.43%  23.2  281s
 69455  3154    0.40033   26   18    0.41022    0.39694  3.24%  23.2  286s
 70309  3032     cutoff   31         0.41022    0.39745  3.11%  23.1  290s
 72172  2584     cutoff   31         0.41022    0.39827  2.91%  23.1  296s
 73467  2321     cutoff   29         0.41022    0.39894  2.75%  23.1  300s
 75181  1902     cutoff   30         0.41022    0.40005  2.48%  23.0  307s
 76651  1353     cutoff   27         0.41022    0.40134  2.16%  22.9  310s

Cutting planes:
  Implied bound: 215
  MIR: 130
  Flow cover: 409
  RLT: 106
  Relax-and-lift: 93

Explored 79766 nodes (1812351 simplex iterations) in 314.63 seconds (251.82 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.410218 0.410218 0.410567 ... 0.482868

Optimal solution found (tolerance 1.00e-04)
Best objective 4.102181421787e-01, best bound 4.102181421787e-01, gap 0.0000%
The objective bound is 0.410218 while the objective upper bound is 0.410218

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

        Job resource usage summary 

                 Memory (GB)    NCPUs
 Requested  :        12             4
 Used       :         0 (peak)   3.68 (ave)

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