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.94134 seconds.
    local lower bound updated to 0.374077
    local upper bound updated to 0.583948

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.678992
    local lower bound updated to 0.674981

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 8.54436 seconds.
    local lower bound updated to 0.456643
    local upper bound updated to 0.614075

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 14.2178 seconds.
    local lower bound updated to 0.602198
    local upper bound updated to 0.606317

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 8.41211 seconds.
    local lower bound updated to 0.602198
    local upper bound updated to 0.602635

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.649677
    local lower bound updated to 0.643986

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.649677
    local lower bound updated to 0.644461

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 48.0177 seconds.
    local lower bound updated to 0.522698
    local upper bound updated to 0.630786

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 7.61359 seconds.
    local lower bound updated to 0.58142
    local upper bound updated to 0.622087

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 21.6915 seconds.
    local lower bound updated to 0.60365
    local upper bound updated to 0.615894

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0142 seconds.
    local lower bound updated to 0.612883
    local upper bound updated to 0.615146

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0203 seconds.
    local lower bound updated to 0.612883
    local upper bound updated to 0.614536

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0184 seconds.
    local lower bound updated to 0.612883
    local upper bound updated to 0.613438

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.630369
    local lower bound updated to 0.613438

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.622993
    local lower bound updated to 0.618297

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.620068
    local lower bound updated to 0.618434

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0155 seconds.
    local lower bound updated to 0.544698
    local upper bound updated to 0.618434

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.607159
    local upper bound updated to 0.617554

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0153 seconds.
    local lower bound updated to 0.613121
    local upper bound updated to 0.616255

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.015 seconds.
    local lower bound updated to 0.613121
    local upper bound updated to 0.616107

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0135 seconds.
    local lower bound updated to 0.614916
    local upper bound updated to 0.615916

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0147 seconds.
    local lower bound updated to 0.615288
    local upper bound updated to 0.615762

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0128 seconds.
    local lower bound updated to 0.615288
    local upper bound updated to 0.615697

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0145 seconds.
    local lower bound updated to 0.615458
    local upper bound updated to 0.615667

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0158 seconds.
    local lower bound updated to 0.615458
    local upper bound updated to 0.615662

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0161 seconds.
    local lower bound updated to 0.61559
    local upper bound updated to 0.615661

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.629721
    local lower bound updated to 0.615661

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.615972
    local lower bound updated to 0.615691

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0144 seconds.
    local lower bound updated to 0.554055
    local upper bound updated to 0.615691

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0131 seconds.
    local lower bound updated to 0.615055
    local upper bound updated to 0.615676

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0131 seconds.
    local lower bound updated to 0.61548
    local upper bound updated to 0.615666

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.630455
    local lower bound updated to 0.615666

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0205 seconds.
    local lower bound updated to 0.554055
    local upper bound updated to 0.615666

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0213 seconds.
    local lower bound updated to 0.615666
    local upper bound updated to 0.615666
The time obtain the optimal randomized assortment is 1392.51 (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_60018.log"
The computation time limit for a lower bound is 84593
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 1634 rows, 1250 columns and 5424 nonzeros
Model fingerprint: 0x042bd386
Variable types: 1185 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [5e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 359 rows and 652 columns
Presolve time: 0.01s
Presolved: 1275 rows, 598 columns, 4951 nonzeros
Variable types: 540 continuous, 58 integer (58 binary)
Found heuristic solution: objective 0.6904856
Found heuristic solution: objective 0.6827565

Root relaxation: objective 4.984944e-01, 140 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.49849    0   22    0.68276    0.49849  27.0%     -    0s
H    0     0                       0.6793318    0.49849  26.6%     -    0s
H    0     0                       0.6739360    0.49849  26.0%     -    0s
H    0     0                       0.6425961    0.50085  22.1%     -    0s
     0     0    0.50128    0   28    0.64260    0.50128  22.0%     -    0s
H    0     0                       0.6357967    0.50128  21.2%     -    0s
     0     0    0.52535    0   28    0.63580    0.52535  17.4%     -    0s
     0     0    0.52563    0   28    0.63580    0.52563  17.3%     -    0s
     0     0    0.53300    0   29    0.63580    0.53300  16.2%     -    0s
     0     0    0.53387    0   31    0.63580    0.53387  16.0%     -    0s
H    0     0                       0.6317652    0.53912  14.7%     -    0s
     0     0    0.53912    0   32    0.63177    0.53912  14.7%     -    0s
     0     0    0.53953    0   31    0.63177    0.53953  14.6%     -    0s
H    0     0                       0.6186576    0.53953  12.8%     -    0s
     0     0    0.54199    0   32    0.61866    0.54199  12.4%     -    0s
H    0     0                       0.6164611    0.54199  12.1%     -    1s
     0     2    0.54199    0   32    0.61646    0.54199  12.1%     -    1s
*  115    97              23       0.6164362    0.55396  10.1%  22.4    2s
H  300   241                       0.6161036    0.55647  9.68%  20.0    3s
H  307   241                       0.6156729    0.55650  9.61%  20.1    3s
H  516   395                       0.6156660    0.55879  9.24%  20.4    4s
   517   398    0.61226   21    7    0.61567    0.55893  9.22%  20.5    5s
  1292   839    0.56882   24   19    0.61567    0.56712  7.89%  20.1   10s
  3256  1056    0.58428   28   16    0.61567    0.57546  6.53%  20.1   15s
  4800  1609    0.60031   24   14    0.61567    0.57793  6.13%  20.1   21s
  5876  2079    0.60788   26   14    0.61567    0.57921  5.92%  20.1   25s
  7366  2590     cutoff   36         0.61567    0.58092  5.64%  20.3   30s
H 8701  2973                       0.6156660    0.58205  5.46%  20.1   34s
  8893  3016    0.61433   32    7    0.61567    0.58224  5.43%  20.1   35s
H 9150  3072                       0.6156660    0.58230  5.42%  20.0   37s
H10475  3522                       0.6156660    0.58316  5.28%  20.0   39s
 10693  3617    0.61215   32    7    0.61567    0.58334  5.25%  20.0   41s
H12026  3912                       0.6156660    0.58405  5.13%  20.1   43s
 12441  4104     cutoff   35         0.61567    0.58433  5.09%  20.0   45s
 13942  4486 infeasible   28         0.61567    0.58536  4.92%  20.1   50s
 16360  5053    0.59000   33   12    0.61567    0.58634  4.76%  19.9   56s
 18015  5369    0.61309   35    7    0.61567    0.58697  4.66%  19.9   60s
 20253  5830     cutoff   33         0.61567    0.58778  4.53%  19.8   65s
 21591  6141    0.60457   31   10    0.61567    0.58827  4.45%  19.8   70s
 23248  6502    0.60557   30   14    0.61567    0.58889  4.35%  19.8   75s
 25103  6891 infeasible   31         0.61567    0.58936  4.27%  19.8   80s
 27552  7228    0.61162   28   14    0.61567    0.58998  4.17%  19.8   85s
 29480  7446    0.60078   31   12    0.61567    0.59045  4.10%  19.7   90s
 30994  7595    0.59728   28   12    0.61567    0.59077  4.04%  19.7   95s
 32383  7777    0.60473   28   15    0.61567    0.59105  4.00%  19.7  100s
H32749  7843                       0.6156660    0.59111  3.99%  19.7  102s
 33581  7973     cutoff   33         0.61567    0.59136  3.95%  19.7  106s
H33837  7973                       0.6156659    0.59137  3.95%  19.7  106s
 35557  8225    0.60694   34   12    0.61567    0.59178  3.88%  19.6  110s
 37594  8444    0.59824   30   12    0.61567    0.59213  3.82%  19.6  115s
 39827  8645    0.59668   34   11    0.61567    0.59263  3.74%  19.6  120s
 41248  8826    0.61178   36    6    0.61567    0.59289  3.70%  19.6  125s
 43889  9012    0.61228   31   11    0.61567    0.59334  3.63%  19.6  130s
 45776  9253 infeasible   29         0.61567    0.59360  3.58%  19.5  136s
 47992  9412    0.61010   31   12    0.61567    0.59396  3.53%  19.5  141s
 49961  9536     cutoff   36         0.61567    0.59431  3.47%  19.5  145s
 51525  9662    0.60350   32   12    0.61567    0.59460  3.42%  19.4  150s
 54406  9906     cutoff   35         0.61567    0.59503  3.35%  19.4  156s
 56943  9985    0.60713   33   14    0.61567    0.59538  3.29%  19.3  160s
 59086 10059     cutoff   28         0.61567    0.59565  3.25%  19.3  166s
 60295 10113 infeasible   34         0.61567    0.59583  3.22%  19.3  170s
 61963 10165    0.60471   36   14    0.61567    0.59607  3.18%  19.3  175s
 64341 10253    0.60688   30   15    0.61567    0.59640  3.13%  19.3  181s
 66357 10299     cutoff   30         0.61567    0.59665  3.09%  19.3  186s
 68327 10359 infeasible   35         0.61567    0.59689  3.05%  19.2  190s
 70603 10378     cutoff   33         0.61567    0.59721  3.00%  19.2  196s
 72361 10385    0.61343   36    4    0.61567    0.59739  2.97%  19.2  200s
H72651 10385                       0.6156659    0.59740  2.97%  19.2  200s
H73324 10397                       0.6156659    0.59753  2.95%  19.2  202s
 73332 10187    0.61174   32   12    0.61567    0.59754  2.94%  19.2  206s
 78012 10164     cutoff   31         0.61567    0.59797  2.87%  19.2  210s
 80344 10113     cutoff   32         0.61567    0.59828  2.82%  19.2  215s
 83032 10048    0.61373   31    8    0.61567    0.59860  2.77%  19.1  220s
 85941  9986    0.61303   28   11    0.61567    0.59896  2.71%  19.1  226s
 87604  9929    0.61152   33    9    0.61567    0.59920  2.68%  19.1  230s
 89989  9849    0.60223   31   15    0.61567    0.59950  2.63%  19.1  235s
 92567  9708    0.61534   27   10    0.61567    0.59991  2.56%  19.1  240s
 95066  9574    0.60428   37   12    0.61567    0.60021  2.51%  19.1  245s
 97338  9403     cutoff   34         0.61567    0.60052  2.46%  19.1  250s
 99307  9228    0.60147   29   15    0.61567    0.60079  2.42%  19.0  255s
 101801  9035     cutoff   33         0.61567    0.60117  2.35%  19.0  260s
H102243  9035                       0.6156659    0.60127  2.34%  19.0  260s
H102421  9030                       0.6156659    0.60127  2.34%  19.0  261s
 103302  8849    0.61140   30    9    0.61567    0.60137  2.32%  19.0  265s
H104349  8817                       0.6156659    0.60154  2.29%  19.0  266s
 105545  8662    0.60755   29   11    0.61567    0.60166  2.27%  19.0  270s
 108822  8238    0.60252   37   10    0.61567    0.60221  2.19%  19.0  276s
H109821  8177                       0.6156659    0.60231  2.17%  19.0  277s
 111274  7970     cutoff   32         0.61567    0.60263  2.12%  19.0  280s
 113327  7738     cutoff   30         0.61567    0.60294  2.07%  19.0  285s
 115652  7372     cutoff   37         0.61567    0.60330  2.01%  18.9  290s
H118324  7019                       0.6156659    0.60375  1.94%  18.9  295s
 120574  6580 infeasible   31         0.61567    0.60413  1.87%  18.9  301s
 123303  5945     cutoff   33         0.61567    0.60465  1.79%  18.9  306s
 125758  5519    0.60837   32    6    0.61567    0.60521  1.70%  18.9  310s
 128535  4777     cutoff   35         0.61567    0.60586  1.59%  18.9  315s
 132523  3729    0.61100   28    6    0.61567    0.60713  1.39%  18.8  320s
 135037  2813    0.60871   28   16    0.61567    0.60816  1.22%  18.8  325s
 136996  2228     cutoff   29         0.61567    0.60933  1.03%  18.7  330s
 139595   270     cutoff   32         0.61567    0.61222  0.56%  18.7  335s

Cutting planes:
  Implied bound: 148
  MIR: 106
  Flow cover: 261
  RLT: 36
  Relax-and-lift: 52

Explored 140278 nodes (2611320 simplex iterations) in 335.48 seconds (291.13 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.615666 0.615666 0.615666 ... 0.635797

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

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

        Job resource usage summary 

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

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