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 0.73378 seconds.
    local lower bound updated to 0.241806
    local upper bound updated to 0.468914

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.614523
    local lower bound updated to 0.599481

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.613743
    local lower bound updated to 0.60703

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.607068
    local lower bound updated to 0.607053

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 28.8959 seconds.
    local lower bound updated to 0.38646
    local upper bound updated to 0.534033

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.33026 seconds.
    local lower bound updated to 0.426393
    local upper bound updated to 0.479302

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 26.3111 seconds.
    local lower bound updated to 0.459574
    local upper bound updated to 0.470701

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 18.3341 seconds.
    local lower bound updated to 0.459574
    local upper bound updated to 0.470258

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 23.2421 seconds.
    local lower bound updated to 0.459574
    local upper bound updated to 0.460676

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 5.22394 seconds.
    local lower bound updated to 0.459574
    local upper bound updated to 0.460663

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 7.26138 seconds.
    local lower bound updated to 0.459648
    local upper bound updated to 0.460578

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 19.1705 seconds.
    local lower bound updated to 0.459648
    local upper bound updated to 0.460578

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 18.6986 seconds.
    local lower bound updated to 0.459913
    local upper bound updated to 0.460168

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 19.7484 seconds.
    local lower bound updated to 0.460168
    local upper bound updated to 0.460168
The Primal step takes 170.246 (S)
lower bound updated to 0.460168

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.557276
    local lower bound updated to 0.528081

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.552416
    local lower bound updated to 0.533275

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.54252
    local lower bound updated to 0.534455

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0195 seconds.
    local lower bound updated to 0.428444
    local upper bound updated to 0.501952

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 33.3999 seconds.
    local lower bound updated to 0.428444
    local upper bound updated to 0.496317

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0113 seconds.
    local lower bound updated to 0.463724
    local upper bound updated to 0.495468

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0173 seconds.
    local lower bound updated to 0.489432
    local upper bound updated to 0.495227

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0241 seconds.
    local lower bound updated to 0.489432
    local upper bound updated to 0.49453

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0234 seconds.
    local lower bound updated to 0.489432
    local upper bound updated to 0.494264

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0181 seconds.
    local lower bound updated to 0.489751
    local upper bound updated to 0.491664

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0187 seconds.
    local lower bound updated to 0.489751
    local upper bound updated to 0.490687

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0173 seconds.
    local lower bound updated to 0.489751
    local upper bound updated to 0.490628

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0207 seconds.
    local lower bound updated to 0.489751
    local upper bound updated to 0.490464

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0161 seconds.
    local lower bound updated to 0.489751
    local upper bound updated to 0.490426

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0153 seconds.
    local lower bound updated to 0.490426
    local upper bound updated to 0.490426
The Primal step takes 693.658 (S)
lower bound updated to 0.490426

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.540698
    local lower bound updated to 0.490426

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.50593
    local lower bound updated to 0.495058

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0158 seconds.
    local lower bound updated to 0.433157
    local upper bound updated to 0.494669

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0173 seconds.
    local lower bound updated to 0.489998
    local upper bound updated to 0.493533

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0146 seconds.
    local lower bound updated to 0.489998
    local upper bound updated to 0.493388

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0149 seconds.
    local lower bound updated to 0.49231
    local upper bound updated to 0.492755

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0162 seconds.
    local lower bound updated to 0.49231
    local upper bound updated to 0.492711

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0234 seconds.
    local lower bound updated to 0.492706
    local upper bound updated to 0.49271
The Primal step takes 360.13 (S)
lower bound updated to 0.492706

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.533459
    local lower bound updated to 0.49271

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0219 seconds.
    local lower bound updated to 0.433157
    local upper bound updated to 0.49271

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0247 seconds.
    local lower bound updated to 0.49271
    local upper bound updated to 0.49271
The time obtain the optimal randomized assortment is 1362.96 (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_63318.log"
The computation time limit for a lower bound is 84620
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 1828 rows, 1398 columns and 5996 nonzeros
Model fingerprint: 0xd0527d95
Variable types: 1333 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        [4e-01, 1e+00]
Presolve removed 421 rows and 753 columns
Presolve time: 0.02s
Presolved: 1407 rows, 645 columns, 5421 nonzeros
Variable types: 581 continuous, 64 integer (64 binary)
Found heuristic solution: objective 0.7470399
Found heuristic solution: objective 0.6149911

Root relaxation: objective 2.402364e-01, 181 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.24024    0   16    0.61499    0.24024  60.9%     -    0s
H    0     0                       0.5072775    0.24024  52.6%     -    0s
     0     0    0.25340    0   16    0.50728    0.25340  50.0%     -    0s
H    0     0                       0.4980970    0.29419  40.9%     -    0s
     0     0    0.29419    0   26    0.49810    0.29419  40.9%     -    0s
     0     0    0.29892    0   26    0.49810    0.29892  40.0%     -    0s
     0     0    0.30744    0   27    0.49810    0.30744  38.3%     -    0s
     0     0    0.30824    0   28    0.49810    0.30824  38.1%     -    0s
     0     0    0.31923    0   29    0.49810    0.31923  35.9%     -    0s
     0     0    0.32195    0   30    0.49810    0.32195  35.4%     -    0s
H    0     0                       0.4963329    0.32195  35.1%     -    0s
     0     0    0.32410    0   29    0.49633    0.32410  34.7%     -    0s
H    0     0                       0.4927102    0.32410  34.2%     -    1s
     0     2    0.32410    0   29    0.49271    0.32410  34.2%     -    1s
H  326   208                       0.4927102    0.33723  31.6%  22.5    2s
   600   369    0.49010   27    4    0.49271    0.33731  31.5%  21.0    5s
H 1400   704                       0.4927102    0.35959  27.0%  22.7   10s
H 1480   679                       0.4927102    0.36683  25.5%  22.9   10s
  2182   740    0.38395   20   20    0.49271    0.38076  22.7%  22.7   15s
H 2700   672                       0.4927102    0.38393  22.1%  22.3   17s
  2980   757    0.48892   27   14    0.49271    0.38579  21.7%  22.5   20s
  3693   993    0.45664   23   14    0.49271    0.39014  20.8%  22.6   25s
  4826  1352    0.40822   20   23    0.49271    0.39653  19.5%  22.3   31s
  5808  1629    0.47523   28   14    0.49271    0.39901  19.0%  21.8   35s
H 6605  1812                       0.4927102    0.40197  18.4%  21.8   38s
H 6980  1840                       0.4927102    0.40291  18.2%  21.7   39s
H 7035  1840                       0.4927102    0.40301  18.2%  21.7   39s
  7051  1908     cutoff   20         0.49271    0.40308  18.2%  21.7   41s
  8075  2217    0.45728   25   14    0.49271    0.40519  17.8%  21.8   45s
  8990  2454    0.44271   24   10    0.49271    0.40714  17.4%  21.7   50s
 11066  2930    0.47407   26   14    0.49271    0.41080  16.6%  21.4   56s
 11661  2979    0.46075   26   12    0.49271    0.41205  16.4%  21.4   60s
 13740  3445    0.49234   29   10    0.49271    0.41594  15.6%  21.1   65s
 15519  3777    0.48570   25   10    0.49271    0.41895  15.0%  20.8   70s
 17234  4008    0.42710   21   16    0.49271    0.42133  14.5%  20.7   75s
 19031  4262    0.48942   28   12    0.49271    0.42317  14.1%  20.7   80s
 20919  4435    0.45877   22   19    0.49271    0.42565  13.6%  20.5   85s
 22514  4668     cutoff   29         0.49271    0.42722  13.3%  20.5   90s
 24167  4779    0.46233   25   14    0.49271    0.42894  12.9%  20.3   96s
 25988  4848    0.46978   28   12    0.49271    0.43036  12.7%  20.3  101s
 27693  4909    0.45962   26   14    0.49271    0.43193  12.3%  20.1  105s
 29174  5023    0.48860   23   16    0.49271    0.43338  12.0%  20.1  111s
 30514  5059     cutoff   29         0.49271    0.43443  11.8%  20.1  115s
 33168  5149    0.46047   25   20    0.49271    0.43662  11.4%  19.9  120s
 35071  5186     cutoff   26         0.49271    0.43826  11.1%  19.9  126s
 36479  5192    0.47707   29    8    0.49271    0.43906  10.9%  19.8  130s
 38287  5200    0.45454   23   16    0.49271    0.44042  10.6%  19.7  135s
 40103  5212    0.47995   22   14    0.49271    0.44169  10.4%  19.6  141s
 41967  5201    0.46396   25   16    0.49271    0.44301  10.1%  19.6  146s
 43984  5171    0.48027   23   14    0.49271    0.44441  9.80%  19.6  151s
 45615  5103     cutoff   30         0.49271    0.44582  9.52%  19.5  156s
 47304  5056    0.47826   25   10    0.49271    0.44690  9.30%  19.5  160s
 48936  4993    0.48735   25   10    0.49271    0.44790  9.09%  19.5  165s
 50613  4878    0.48275   27   12    0.49271    0.44878  8.92%  19.4  170s
 53536  4716    0.48439   27   12    0.49271    0.45123  8.42%  19.4  176s
 54676  4650     cutoff   25         0.49271    0.45196  8.27%  19.3  180s
 56030  4542    0.48955   28   16    0.49271    0.45253  8.15%  19.3  185s
 57801  4390     cutoff   30         0.49271    0.45391  7.88%  19.2  190s
 60345  4123     cutoff   27         0.49271    0.45571  7.51%  19.2  195s
 61328  3996    0.48495   26   12    0.49271    0.45667  7.32%  19.2  200s
 64110  3574    0.48555   27   14    0.49271    0.45915  6.81%  19.1  206s
 66075  3337    0.49089   24   14    0.49271    0.46145  6.35%  19.1  210s
 67328  3017     cutoff   26         0.49271    0.46253  6.13%  19.1  216s
 69352  2728    0.47711   22   16    0.49271    0.46438  5.75%  19.0  220s
 70703  2346     cutoff   25         0.49271    0.46609  5.40%  19.0  225s
 73588  1526    0.48923   24   14    0.49271    0.47140  4.32%  18.9  230s

Cutting planes:
  Implied bound: 190
  MIR: 110
  Flow cover: 259
  RLT: 71
  Relax-and-lift: 51

Explored 77008 nodes (1438163 simplex iterations) in 234.02 seconds (183.54 work units)
Thread count was 4 (of 48 available processors)

Solution count 9: 0.49271 0.49271 0.49271 ... 0.74704

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

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

        Job resource usage summary 

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

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