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.48189 seconds.
    local lower bound updated to 0.2403
    local upper bound updated to 0.457172

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.613563
    local lower bound updated to 0.598788

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.607321
    local lower bound updated to 0.607321
The Dual step takes 0.068122 (S)
upper bound updated to 0.607321
*** OUTER ITERATION 1 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.73943 seconds.
    local lower bound updated to 0.322265
    local upper bound updated to 0.453883

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 14.0484 seconds.
    local lower bound updated to 0.446363
    local upper bound updated to 0.45099

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 18.2973 seconds.
    local lower bound updated to 0.44941
    local upper bound updated to 0.450439

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 30.569 seconds.
    local lower bound updated to 0.449803
    local upper bound updated to 0.450056

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 21.4713 seconds.
    local lower bound updated to 0.450056
    local upper bound updated to 0.450056
The Primal step takes 86.1396 (S)
lower bound updated to 0.450056

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.519284
    local lower bound updated to 0.450183

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.478054
    local lower bound updated to 0.476722

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.478054
    local lower bound updated to 0.476722

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 4.08395 seconds.
    local lower bound updated to 0.361472
    local upper bound updated to 0.476722

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.912892 seconds.
    local lower bound updated to 0.423947
    local upper bound updated to 0.466681

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 8.17068 seconds.
    local lower bound updated to 0.447357
    local upper bound updated to 0.459699

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 30.758 seconds.
    local lower bound updated to 0.449386
    local upper bound updated to 0.45525

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 24.0197 seconds.
    local lower bound updated to 0.452191
    local upper bound updated to 0.455016

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 22.0574 seconds.
    local lower bound updated to 0.453641
    local upper bound updated to 0.454883

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 24.4201 seconds.
    local lower bound updated to 0.453641
    local upper bound updated to 0.454373

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 28.573 seconds.
    local lower bound updated to 0.453641
    local upper bound updated to 0.454361

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 31.6482 seconds.
    local lower bound updated to 0.454274
    local upper bound updated to 0.454355

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 21.7539 seconds.
    local lower bound updated to 0.454281
    local upper bound updated to 0.454334

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 25.3348 seconds.
    local lower bound updated to 0.454334
    local upper bound updated to 0.454334
The Primal step takes 221.779 (S)
lower bound updated to 0.454334

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.506579
    local lower bound updated to 0.454334

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.467809
    local lower bound updated to 0.454353

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.467809
    local lower bound updated to 0.455034

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.46333
    local lower bound updated to 0.457549

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.46333
    local lower bound updated to 0.457776

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.459281
    local lower bound updated to 0.45825

    ++ RESTRICTED DUAL ITERATION 7 ++
    local upper bound updated to 0.45897
    local lower bound updated to 0.458409

    ++ RESTRICTED DUAL ITERATION 8 ++
    local upper bound updated to 0.458476
    local lower bound updated to 0.458447

    ++ RESTRICTED DUAL ITERATION 9 ++
    local upper bound updated to 0.458447
    local lower bound updated to 0.458447
The Dual step takes 18.2652 (S)
upper bound updated to 0.458447
*** OUTER ITERATION 3 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 25.4045 seconds.
    local lower bound updated to 0.404157
    local upper bound updated to 0.458447

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0181 seconds.
    local lower bound updated to 0.444558
    local upper bound updated to 0.458426

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0216 seconds.
    local lower bound updated to 0.449419
    local upper bound updated to 0.458106

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0209 seconds.
    local lower bound updated to 0.453584
    local upper bound updated to 0.457847

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.453584
    local upper bound updated to 0.457769

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0176 seconds.
    local lower bound updated to 0.454633
    local upper bound updated to 0.457496

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0172 seconds.
    local lower bound updated to 0.455177
    local upper bound updated to 0.457361

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0296 seconds.
    local lower bound updated to 0.455177
    local upper bound updated to 0.457081

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0381 seconds.
    local lower bound updated to 0.455177
    local upper bound updated to 0.456811

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0209 seconds.
    local lower bound updated to 0.455177
    local upper bound updated to 0.456721

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0172 seconds.
    local lower bound updated to 0.455413
    local upper bound updated to 0.456652

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0162 seconds.
    local lower bound updated to 0.455413
    local upper bound updated to 0.456579

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 60.0233 seconds.
    local lower bound updated to 0.455413
    local upper bound updated to 0.456533

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.455533
    local upper bound updated to 0.456469

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 60.0166 seconds.
    local lower bound updated to 0.455883
    local upper bound updated to 0.456467

    ++ RESTRICTED PRIMAL ITERATION 16 ++
One worst-case parameter optimization takes 60.0174 seconds.
    local lower bound updated to 0.455883
    local upper bound updated to 0.456464

    ++ RESTRICTED PRIMAL ITERATION 17 ++
One worst-case parameter optimization takes 60.0171 seconds.
    local lower bound updated to 0.45599
    local upper bound updated to 0.456447

    ++ RESTRICTED PRIMAL ITERATION 18 ++
One worst-case parameter optimization takes 60.0247 seconds.
    local lower bound updated to 0.456305
    local upper bound updated to 0.456431

    ++ RESTRICTED PRIMAL ITERATION 19 ++
One worst-case parameter optimization takes 60.0249 seconds.
    local lower bound updated to 0.456305
    local upper bound updated to 0.456426

    ++ RESTRICTED PRIMAL ITERATION 20 ++
One worst-case parameter optimization takes 60.0263 seconds.
    local lower bound updated to 0.456325
    local upper bound updated to 0.456404

    ++ RESTRICTED PRIMAL ITERATION 21 ++
One worst-case parameter optimization takes 60.018 seconds.
    local lower bound updated to 0.456325
    local upper bound updated to 0.456395

    ++ RESTRICTED PRIMAL ITERATION 22 ++
One worst-case parameter optimization takes 60.0177 seconds.
    local lower bound updated to 0.456325
    local upper bound updated to 0.456393

    ++ RESTRICTED PRIMAL ITERATION 23 ++
One worst-case parameter optimization takes 60.0214 seconds.
    local lower bound updated to 0.456325
    local upper bound updated to 0.456392

    ++ RESTRICTED PRIMAL ITERATION 24 ++
One worst-case parameter optimization takes 60.0262 seconds.
    local lower bound updated to 0.456347
    local upper bound updated to 0.456392

    ++ RESTRICTED PRIMAL ITERATION 25 ++
One worst-case parameter optimization takes 60.0216 seconds.
    local lower bound updated to 0.456362
    local upper bound updated to 0.456391

    ++ RESTRICTED PRIMAL ITERATION 26 ++
One worst-case parameter optimization takes 60.0243 seconds.
    local lower bound updated to 0.456373
    local upper bound updated to 0.456391

    ++ RESTRICTED PRIMAL ITERATION 27 ++
One worst-case parameter optimization takes 60.0167 seconds.
    local lower bound updated to 0.456391
    local upper bound updated to 0.456391
The Primal step takes 1586.15 (S)
lower bound updated to 0.456391

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.486616
    local lower bound updated to 0.456391

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.468358
    local lower bound updated to 0.456986

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.460916
    local lower bound updated to 0.457169

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.459456
    local lower bound updated to 0.457438

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.458153
    local lower bound updated to 0.457447

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.457543
    local lower bound updated to 0.457448

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 33.8772 seconds.
    local lower bound updated to 0.414362
    local upper bound updated to 0.457448

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0344 seconds.
    local lower bound updated to 0.456883
    local upper bound updated to 0.457444

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0274 seconds.
    local lower bound updated to 0.457185
    local upper bound updated to 0.457404

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0218 seconds.
    local lower bound updated to 0.457345
    local upper bound updated to 0.457404

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0216 seconds.
    local lower bound updated to 0.457404
    local upper bound updated to 0.457404
The Primal step takes 274.033 (S)
lower bound updated to 0.457404

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.479602
    local lower bound updated to 0.457404

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 30.6183 seconds.
    local lower bound updated to 0.414362
    local upper bound updated to 0.457404

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0219 seconds.
    local lower bound updated to 0.457404
    local upper bound updated to 0.457404
The time obtain the optimal randomized assortment is 2351.82 (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_60.000000_66918.log"
The computation time limit for a lower bound is 83634
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 24 physical cores, 48 logical processors, using up to 4 threads
Optimize a model with 3768 rows, 2878 columns and 12403 nonzeros
Model fingerprint: 0xb67fe00f
Variable types: 2813 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 887 rows and 1617 columns
Presolve time: 0.04s
Presolved: 2881 rows, 1261 columns, 11395 nonzeros
Variable types: 1198 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6097585
Found heuristic solution: objective 0.5655910
Found heuristic solution: objective 0.5447139

Root relaxation: objective 3.428522e-01, 269 iterations, 0.01 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.34285    0   32    0.54471    0.34285  37.1%     -    0s
H    0     0                       0.4836576    0.34285  29.1%     -    0s
H    0     0                       0.4821580    0.34285  28.9%     -    0s
H    0     0                       0.4793036    0.34285  28.5%     -    0s
     0     0    0.34777    0   30    0.47930    0.34777  27.4%     -    0s
H    0     0                       0.4679290    0.34777  25.7%     -    0s
     0     0    0.38396    0   33    0.46793    0.38396  17.9%     -    0s
H    0     0                       0.4582652    0.38396  16.2%     -    0s
     0     0    0.38410    0   34    0.45827    0.38410  16.2%     -    0s
     0     0    0.39763    0   32    0.45827    0.39763  13.2%     -    0s
     0     0    0.39843    0   34    0.45827    0.39843  13.1%     -    0s
     0     0    0.40142    0   34    0.45827    0.40142  12.4%     -    0s
     0     0    0.40219    0   34    0.45827    0.40219  12.2%     -    0s
     0     0    0.40329    0   35    0.45827    0.40329  12.0%     -    1s
H    0     0                       0.4574755    0.40329  11.8%     -    5s
     0     2    0.40329    0   35    0.45748    0.40329  11.8%     -    5s
*  230   135              25       0.4574040    0.40951  10.5%  44.4    6s
   697   313    0.42524    9   21    0.45740    0.41653  8.94%  43.7   10s
  1400   492    0.42955   10   22    0.45740    0.42073  8.02%  39.8   16s
  2493   774     cutoff   14         0.45740    0.42443  7.21%  40.1   21s
  2973   876    0.44576   10   19    0.45740    0.42542  6.99%  39.6   25s
  3137   891    0.44072   13   33    0.45740    0.42557  6.96%  39.1   32s
  3312   967    0.44691   23   20    0.45740    0.42557  6.96%  41.9   35s
* 3408   925              35       0.4572667    0.42557  6.93%  42.0   35s
H 3653   902                       0.4571472    0.42557  6.91%  42.6   36s
  4693   864    0.43909   23   19    0.45715    0.42679  6.64%  46.1   40s
  5566   853    0.44495   24   16    0.45715    0.42973  6.00%  47.5   45s
  6081   937    0.44190   27   18    0.45715    0.43142  5.63%  47.9   52s
  6325   984     cutoff   29         0.45715    0.43208  5.48%  48.1   55s
  6701  1046    0.45319   23   22    0.45715    0.43280  5.33%  48.2   60s
  7474  1220    0.44542   28   12    0.45715    0.43443  4.97%  48.0   65s
  8343  1412    0.44864   26   14    0.45715    0.43561  4.71%  48.0   71s
  8698  1456    0.44023   22   21    0.45715    0.43595  4.64%  47.9   76s
H 8700  1456                       0.4571472    0.43595  4.64%  47.9   76s
  9583  1575    0.45448   27   18    0.45715    0.43708  4.39%  47.6   80s
H 9631  1575                       0.4571472    0.43717  4.37%  47.6   80s
  9972  1654    0.45509   25   18    0.45715    0.43750  4.30%  47.6   86s
 10286  1716    0.45086   24   18    0.45715    0.43776  4.24%  47.5   90s
 11225  1873     cutoff   31         0.45715    0.43856  4.07%  47.2   95s
 11942  1987 infeasible   32         0.45715    0.43930  3.90%  46.6  100s
 13325  2082    0.44977   34   13    0.45715    0.44031  3.68%  45.9  105s
 15123  2205     cutoff   30         0.45715    0.44147  3.43%  45.3  112s
 15558  2218    0.44582   29   14    0.45715    0.44175  3.37%  45.1  115s
 16712  2332    0.44678   29   23    0.45715    0.44221  3.27%  44.7  120s
 18050  2403     cutoff   26         0.45715    0.44290  3.12%  44.1  125s
H18200  2403                       0.4571472    0.44290  3.12%  44.1  125s
 19047  2464    0.45144   34   10    0.45715    0.44328  3.03%  43.8  130s
 19643  2494     cutoff   24         0.45715    0.44356  2.97%  43.6  135s
 21068  2542     cutoff   39         0.45715    0.44416  2.84%  43.1  140s
 22497  2543    0.45559   30   10    0.45715    0.44454  2.76%  42.5  145s
 24038  2599     cutoff   25         0.45715    0.44525  2.60%  41.8  152s
 24807  2608     cutoff   31         0.45715    0.44545  2.56%  41.6  156s
 25618  2600    0.44687   31   19    0.45715    0.44577  2.49%  41.3  160s
 26735  2631    0.45640   35    8    0.45715    0.44605  2.43%  41.0  166s
 28052  2635    0.45551   28   15    0.45715    0.44656  2.32%  40.6  171s
 29770  2636     cutoff   34         0.45715    0.44701  2.22%  40.0  176s
 30959  2576     cutoff   28         0.45715    0.44744  2.12%  39.7  181s
 32070  2524    0.45134   31   12    0.45715    0.44775  2.06%  39.4  188s
 32614  2479    0.45606   38    4    0.45715    0.44783  2.04%  39.3  190s
 34217  2431    0.45277   30   15    0.45715    0.44831  1.93%  38.8  196s
 35222  2365    0.45306   33    8    0.45715    0.44873  1.84%  38.6  200s
 37181  2241     cutoff   31         0.45715    0.44922  1.73%  38.2  205s
 38283  2134    0.45649   36    6    0.45715    0.44961  1.65%  37.9  211s
 40177  1971     cutoff   40         0.45715    0.45027  1.50%  37.4  216s
 41905  1720     cutoff   36         0.45715    0.45082  1.38%  36.9  221s
H41907  1720                       0.4571472    0.45082  1.38%  36.9  221s
 43717  1364    0.45540   31   10    0.45715    0.45155  1.22%  36.5  225s
 45690   843     cutoff   33         0.45715    0.45269  0.97%  35.9  231s
 47750   292     cutoff   34         0.45715    0.45476  0.52%  35.3  236s

Cutting planes:
  Gomory: 1
  Implied bound: 293
  MIR: 236
  Flow cover: 695
  RLT: 146
  Relax-and-lift: 81

Explored 48302 nodes (1694431 simplex iterations) in 238.99 seconds (211.82 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.457147 0.457147 0.457267 ... 0.483658

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

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

        Job resource usage summary 

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

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