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 1.20466 seconds.
    local lower bound updated to 0.212518
    local upper bound updated to 0.419281

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.526906
    local lower bound updated to 0.513714

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.72514 seconds.
    local lower bound updated to 0.282027
    local upper bound updated to 0.423326

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.50092 seconds.
    local lower bound updated to 0.415048
    local upper bound updated to 0.421504

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 2.26464 seconds.
    local lower bound updated to 0.419012
    local upper bound updated to 0.419363

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.64021 seconds.
    local lower bound updated to 0.419012
    local upper bound updated to 0.419068

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.499826
    local lower bound updated to 0.44688

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.481294
    local lower bound updated to 0.472562

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.481294
    local lower bound updated to 0.472947

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.478142
    local lower bound updated to 0.475867

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.475867
    local lower bound updated to 0.475867
The Dual step takes 0.804436 (S)
upper bound updated to 0.475867
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 7.22739 seconds.
    local lower bound updated to 0.347483
    local upper bound updated to 0.428103

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.31048 seconds.
    local lower bound updated to 0.413177
    local upper bound updated to 0.427126

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 10.4441 seconds.
    local lower bound updated to 0.42148
    local upper bound updated to 0.42557

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 4.8549 seconds.
    local lower bound updated to 0.42148
    local upper bound updated to 0.423475

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 9.7653 seconds.
    local lower bound updated to 0.423241
    local upper bound updated to 0.423456

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 6.40303 seconds.
    local lower bound updated to 0.423351
    local upper bound updated to 0.423438

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.465904
    local lower bound updated to 0.426899

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.465904
    local lower bound updated to 0.443735

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.465904
    local lower bound updated to 0.451464

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.457697
    local lower bound updated to 0.452147

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.453815
    local lower bound updated to 0.452291

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 47.9808 seconds.
    local lower bound updated to 0.379873
    local upper bound updated to 0.45015

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 4.34659 seconds.
    local lower bound updated to 0.409577
    local upper bound updated to 0.440946

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0162 seconds.
    local lower bound updated to 0.432776
    local upper bound updated to 0.440923

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0234 seconds.
    local lower bound updated to 0.437949
    local upper bound updated to 0.440702

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.437949
    local upper bound updated to 0.440444

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.022 seconds.
    local lower bound updated to 0.438178
    local upper bound updated to 0.440398

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0202 seconds.
    local lower bound updated to 0.438476
    local upper bound updated to 0.440199

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.438637
    local upper bound updated to 0.440042

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0248 seconds.
    local lower bound updated to 0.439968
    local upper bound updated to 0.440035

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0199 seconds.
    local lower bound updated to 0.439984
    local upper bound updated to 0.440034

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.459602
    local lower bound updated to 0.440034

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.452375
    local lower bound updated to 0.443671

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.452375
    local lower bound updated to 0.444496

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.446412
    local lower bound updated to 0.444721

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.446121
    local lower bound updated to 0.444834

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0215 seconds.
    local lower bound updated to 0.396192
    local upper bound updated to 0.444834

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0274 seconds.
    local lower bound updated to 0.434725
    local upper bound updated to 0.443026

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.025 seconds.
    local lower bound updated to 0.43962
    local upper bound updated to 0.442878

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0207 seconds.
    local lower bound updated to 0.441355
    local upper bound updated to 0.44283

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0279 seconds.
    local lower bound updated to 0.441943
    local upper bound updated to 0.442819

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0193 seconds.
    local lower bound updated to 0.441943
    local upper bound updated to 0.442735

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0188 seconds.
    local lower bound updated to 0.441943
    local upper bound updated to 0.442711

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0202 seconds.
    local lower bound updated to 0.442112
    local upper bound updated to 0.442689

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0234 seconds.
    local lower bound updated to 0.442321
    local upper bound updated to 0.442658

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0177 seconds.
    local lower bound updated to 0.442433
    local upper bound updated to 0.442632

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.458568
    local lower bound updated to 0.442632

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.449175
    local lower bound updated to 0.442876

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.446831
    local lower bound updated to 0.443087

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.443839
    local lower bound updated to 0.443184

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0371 seconds.
    local lower bound updated to 0.39741
    local upper bound updated to 0.443184

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0306 seconds.
    local lower bound updated to 0.442701
    local upper bound updated to 0.443126

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0288 seconds.
    local lower bound updated to 0.442793
    local upper bound updated to 0.443109

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0288 seconds.
    local lower bound updated to 0.443109
    local upper bound updated to 0.443109
The Primal step takes 240.172 (S)
lower bound updated to 0.443109

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.453548
    local lower bound updated to 0.443109

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.443411
    local lower bound updated to 0.443124

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0294 seconds.
    local lower bound updated to 0.396081
    local upper bound updated to 0.443124

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0265 seconds.
    local lower bound updated to 0.443009
    local upper bound updated to 0.443123

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0291 seconds.
    local lower bound updated to 0.443048
    local upper bound updated to 0.443121

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0323 seconds.
    local lower bound updated to 0.443121
    local upper bound updated to 0.443121
The Primal step takes 240.159 (S)
lower bound updated to 0.443121

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.454941
    local lower bound updated to 0.443121

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0279 seconds.
    local lower bound updated to 0.396081
    local upper bound updated to 0.443121

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0297 seconds.
    local lower bound updated to 0.443121
    local upper bound updated to 0.443121
The time obtain the optimal randomized assortment is 1985.93 (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_60918.log"
The computation time limit for a lower bound is 84007
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 3962 rows, 3026 columns and 13111 nonzeros
Model fingerprint: 0x21075853
Variable types: 2961 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [1e-01, 1e+00]
Presolve removed 872 rows and 1670 columns
Presolve time: 0.05s
Presolved: 3090 rows, 1356 columns, 12224 nonzeros
Variable types: 1293 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6591851
Found heuristic solution: objective 0.6497736
Found heuristic solution: objective 0.6109864
Found heuristic solution: objective 0.5455359

Root relaxation: objective 2.468893e-01, 344 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.24689    0   25    0.54554    0.24689  54.7%     -    0s
H    0     0                       0.4969968    0.24689  50.3%     -    0s
     0     0    0.26048    0   31    0.49700    0.26048  47.6%     -    0s
     0     0    0.26048    0   31    0.49700    0.26048  47.6%     -    0s
     0     0    0.31745    0   31    0.49700    0.31745  36.1%     -    0s
H    0     0                       0.4588744    0.31745  30.8%     -    0s
     0     0    0.31746    0   32    0.45887    0.31746  30.8%     -    0s
H    0     0                       0.4546159    0.31746  30.2%     -    0s
     0     0    0.33733    0   30    0.45462    0.33733  25.8%     -    0s
     0     0    0.33836    0   29    0.45462    0.33836  25.6%     -    0s
H    0     0                       0.4544285    0.34944  23.1%     -    1s
     0     0    0.34944    0   32    0.45443    0.34944  23.1%     -    1s
H    0     0                       0.4437037    0.34944  21.2%     -    1s
     0     0    0.35049    0   34    0.44370    0.35049  21.0%     -    1s
     0     0    0.35255    0   31    0.44370    0.35255  20.5%     -    1s
H    0     0                       0.4431213    0.35255  20.4%     -    5s
     0     2    0.35255    0   31    0.44312    0.35255  20.4%     -    5s
   198   122    0.38715    8   22    0.44312    0.37246  15.9%  38.6   10s
   500   260    0.40986    9   20    0.44312    0.38175  13.9%  37.9   16s
  1033   475    0.42438   18   10    0.44312    0.38647  12.8%  34.7   20s
  1280   572    0.39538    7   18    0.44312    0.39002  12.0%  33.2   25s
  1566   666     cutoff   22         0.44312    0.39206  11.5%  32.5   32s
  2089   799    0.40295   10   31    0.44312    0.39395  11.1%  32.0   35s
  2109   812    0.43702   19   33    0.44312    0.39395  11.1%  31.7   41s
  2552   898    0.42566   24   14    0.44312    0.39710  10.4%  37.1   45s
  2798   882    0.40918   19   20    0.44312    0.39875  10.0%  38.3   51s
  3137   881    0.41679   22   18    0.44312    0.40139  9.42%  39.4   55s
  3975   863    0.43761   29    6    0.44312    0.40663  8.23%  39.4   61s
  4197   827    0.42005   24   12    0.44312    0.40700  8.15%  39.3   65s
  4587   815    0.42769   28    8    0.44312    0.40809  7.91%  39.8   70s
  5006   912    0.43782   22   16    0.44312    0.40904  7.69%  39.8   75s
  5372  1000    0.43441   22   16    0.44312    0.40979  7.52%  39.9   82s
  5802  1072    0.41823   24   18    0.44312    0.41062  7.33%  39.7   85s
  6207  1085     cutoff   29         0.44312    0.41126  7.19%  39.8   90s
  7467  1321    0.43761   27   11    0.44312    0.41406  6.56%  39.9   96s
  8101  1425    0.42935   28   14    0.44312    0.41514  6.32%  40.0  100s
  8946  1500    0.42275   22   18    0.44312    0.41690  5.92%  39.4  107s
  9306  1567     cutoff   29         0.44312    0.41749  5.78%  39.2  112s
  9686  1603     cutoff   26         0.44312    0.41777  5.72%  39.0  115s
 10463  1683    0.42900   28   17    0.44312    0.41872  5.51%  38.7  120s
 11335  1782    0.42942   21   18    0.44312    0.41972  5.28%  38.3  128s
 12251  1781 infeasible   29         0.44312    0.42002  5.21%  37.9  131s
 13307  1789    0.42310   25   17    0.44312    0.42162  4.85%  37.6  137s
 14135  1797    0.43866   27   12    0.44312    0.42253  4.65%  37.4  140s
 15840  1766     cutoff   31         0.44312    0.42390  4.34%  37.0  148s
 16184  1741     cutoff   31         0.44312    0.42440  4.23%  37.0  150s
 18122  1611     cutoff   25         0.44312    0.42581  3.91%  36.5  156s
 18801  1564    0.43318   27    9    0.44312    0.42652  3.75%  36.3  160s
 19790  1472     cutoff   30         0.44312    0.42740  3.55%  36.1  165s
 21031  1324    0.43880   26   15    0.44312    0.42832  3.34%  35.8  171s
 22460  1169    0.43856   20   23    0.44312    0.42928  3.12%  35.5  177s
 22650  1040    0.44007   27   11    0.44312    0.42985  3.00%  35.4  180s
 24028   766     cutoff   29         0.44312    0.43142  2.64%  35.1  185s
 25386   569    0.43719   26    4    0.44312    0.43293  2.30%  34.7  190s

Cutting planes:
  Implied bound: 301
  MIR: 210
  Flow cover: 736
  RLT: 73
  Relax-and-lift: 121

Explored 27088 nodes (923300 simplex iterations) in 193.63 seconds (164.68 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.443121 0.443704 0.454428 ... 0.659185

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

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

        Job resource usage summary 

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

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