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.305521 seconds.
    local lower bound updated to 0.165512
    local upper bound updated to 0.308603

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

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.60833 seconds.
    local lower bound updated to 0.216229
    local upper bound updated to 0.327764

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.93366 seconds.
    local lower bound updated to 0.315573
    local upper bound updated to 0.318821

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 3.84539 seconds.
    local lower bound updated to 0.318821
    local upper bound updated to 0.318821
The Primal step takes 7.3913 (S)
lower bound updated to 0.318821

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.355439
    local lower bound updated to 0.347198

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.355439
    local lower bound updated to 0.348412

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.353576
    local lower bound updated to 0.349328

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 20.0101 seconds.
    local lower bound updated to 0.273733
    local upper bound updated to 0.332831

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 12.5032 seconds.
    local lower bound updated to 0.323057
    local upper bound updated to 0.331354

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 9.76259 seconds.
    local lower bound updated to 0.328414
    local upper bound updated to 0.32989

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 16.2384 seconds.
    local lower bound updated to 0.328414
    local upper bound updated to 0.329626

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 14.2843 seconds.
    local lower bound updated to 0.328414
    local upper bound updated to 0.329354

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 16.8487 seconds.
    local lower bound updated to 0.328993
    local upper bound updated to 0.329224

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 14.7654 seconds.
    local lower bound updated to 0.328993
    local upper bound updated to 0.329185

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 16.5337 seconds.
    local lower bound updated to 0.329144
    local upper bound updated to 0.329182

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.342649
    local lower bound updated to 0.330629

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.338562
    local lower bound updated to 0.331461

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.338562
    local lower bound updated to 0.333744

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.338372
    local lower bound updated to 0.334941

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.336922
    local lower bound updated to 0.334952

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.336922
    local lower bound updated to 0.335263

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0171 seconds.
    local lower bound updated to 0.303776
    local upper bound updated to 0.335227

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0188 seconds.
    local lower bound updated to 0.330534
    local upper bound updated to 0.334872

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0171 seconds.
    local lower bound updated to 0.332819
    local upper bound updated to 0.334809

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0182 seconds.
    local lower bound updated to 0.332819
    local upper bound updated to 0.334677

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0202 seconds.
    local lower bound updated to 0.333336
    local upper bound updated to 0.334634

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0181 seconds.
    local lower bound updated to 0.334016
    local upper bound updated to 0.334578

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0241 seconds.
    local lower bound updated to 0.334016
    local upper bound updated to 0.33456

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.02 seconds.
    local lower bound updated to 0.334016
    local upper bound updated to 0.334542

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0241 seconds.
    local lower bound updated to 0.334178
    local upper bound updated to 0.334524

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0201 seconds.
    local lower bound updated to 0.334447
    local upper bound updated to 0.334517

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0276 seconds.
    local lower bound updated to 0.334447
    local upper bound updated to 0.334482

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0181 seconds.
    local lower bound updated to 0.334447
    local upper bound updated to 0.334476

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 60.0186 seconds.
    local lower bound updated to 0.33446
    local upper bound updated to 0.334476

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.334464
    local upper bound updated to 0.334475

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 60.0181 seconds.
    local lower bound updated to 0.334475
    local upper bound updated to 0.334475
The Primal step takes 900.359 (S)
lower bound updated to 0.334475

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.345959
    local lower bound updated to 0.334475

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.341731
    local lower bound updated to 0.33466

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.335383
    local lower bound updated to 0.334716

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.306642
    local upper bound updated to 0.334716

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0166 seconds.
    local lower bound updated to 0.334218
    local upper bound updated to 0.334689

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0185 seconds.
    local lower bound updated to 0.334465
    local upper bound updated to 0.334689

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0201 seconds.
    local lower bound updated to 0.334645
    local upper bound updated to 0.334685

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.348469
    local lower bound updated to 0.334685

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.334927
    local lower bound updated to 0.334692

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0286 seconds.
    local lower bound updated to 0.308127
    local upper bound updated to 0.334692

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0272 seconds.
    local lower bound updated to 0.334502
    local upper bound updated to 0.334692

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0228 seconds.
    local lower bound updated to 0.334647
    local upper bound updated to 0.334691

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.026 seconds.
    local lower bound updated to 0.334672
    local upper bound updated to 0.33469

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0229 seconds.
    local lower bound updated to 0.33469
    local upper bound updated to 0.33469
The time obtain the optimal randomized assortment is 1683.31 (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_69518.log"
The computation time limit for a lower bound is 84309
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 128 physical cores, 256 logical processors, using up to 4 threads
Optimize a model with 2992 rows, 2286 columns and 9829 nonzeros
Model fingerprint: 0xb39bbe16
Variable types: 2221 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 684 rows and 1264 columns
Presolve time: 0.02s
Presolved: 2308 rows, 1022 columns, 8904 nonzeros
Variable types: 959 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.4929384
Found heuristic solution: objective 0.3988641

Root relaxation: objective 1.223945e-01, 244 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.12239    0   24    0.39886    0.12239  69.3%     -    0s
     0     0    0.12369    0   24    0.39886    0.12369  69.0%     -    0s
     0     0    0.14381    0   24    0.39886    0.14381  63.9%     -    0s
     0     0    0.19525    0   30    0.39886    0.19525  51.0%     -    0s
H    0     0                       0.3522697    0.19525  44.6%     -    0s
     0     0    0.20935    0   33    0.35227    0.20935  40.6%     -    0s
     0     0    0.21026    0   34    0.35227    0.21026  40.3%     -    0s
     0     0    0.21595    0   35    0.35227    0.21595  38.7%     -    0s
     0     0    0.21680    0   35    0.35227    0.21680  38.5%     -    0s
     0     0    0.21788    0   36    0.35227    0.21788  38.1%     -    0s
H    0     0                       0.3354344    0.21788  35.0%     -    0s
H    0     0                       0.3348101    0.21788  34.9%     -    2s
     0     2    0.21788    0   36    0.33481    0.21788  34.9%     -    2s
H   53    58                       0.3346904    0.22565  32.6%  53.8    2s
   720   376    0.29907   12   18    0.33469    0.24481  26.9%  32.2    5s
  2093   852    0.31440   18   38    0.33469    0.25870  22.7%  29.5   10s
  2497   931    0.26234   25   23    0.33469    0.25870  22.7%  32.5   15s
  3471   931    0.30403   21   21    0.33469    0.25996  22.3%  35.3   20s
H 4608   843                       0.3346904    0.26639  20.4%  35.8   22s
  5062   974    0.31624   27   12    0.33469    0.26938  19.5%  36.3   26s
  5894  1132    0.31398   33   12    0.33469    0.27150  18.9%  36.5   30s
  6798  1398    0.27738   21   27    0.33469    0.27552  17.7%  36.8   35s
  7871  1663    0.31969   30   17    0.33469    0.27827  16.9%  36.5   41s
H 8840  1816                       0.3346904    0.27993  16.4%  35.9   43s
  9365  1989    0.31442   28   15    0.33469    0.28165  15.8%  35.8   45s
 10832  2311    0.29770   26   20    0.33469    0.28460  15.0%  35.5   50s
H10901  2311                       0.3346904    0.28460  15.0%  35.5   50s
 12180  2581     cutoff   28         0.33469    0.28716  14.2%  35.1   55s
H12222  2581                       0.3346904    0.28717  14.2%  35.1   55s
 13983  2852    0.31336   32   16    0.33469    0.28946  13.5%  34.7   61s
 15508  3071 infeasible   32         0.33469    0.29114  13.0%  34.5   66s
H15650  3071                       0.3346904    0.29114  13.0%  34.4   66s
 17355  3329    0.31940   28   18    0.33469    0.29331  12.4%  34.3   70s
 19273  3471     cutoff   29         0.33469    0.29519  11.8%  34.1   76s
 20757  3635     cutoff   31         0.33469    0.29643  11.4%  33.9   80s
 22743  3834     cutoff   34         0.33469    0.29799  11.0%  33.6   85s
 25177  3991    0.31558   28   21    0.33469    0.29980  10.4%  33.3   91s
 27257  4169    0.32959   33   14    0.33469    0.30148  9.92%  32.9   96s
 29373  4210    0.32163   38   12    0.33469    0.30265  9.57%  32.7  100s
 30960  4240    0.31871   27   16    0.33469    0.30372  9.25%  32.5  105s
 33981  4313     cutoff   37         0.33469    0.30546  8.73%  32.2  110s
 35210  4344    0.32544   30   14    0.33469    0.30602  8.57%  32.1  116s
 36546  4353     cutoff   32         0.33469    0.30679  8.34%  31.9  120s
 39436  4377     cutoff   33         0.33469    0.30807  7.96%  31.6  125s
 41209  4382    0.32777   27   14    0.33469    0.30905  7.66%  31.4  130s
 44268  4351 infeasible   39         0.33469    0.31061  7.20%  31.1  135s
 45903  4276    0.32054   34   15    0.33469    0.31131  6.99%  31.0  140s
 48116  4188     cutoff   37         0.33469    0.31220  6.72%  30.8  145s
 50896  4084    0.32178   35   14    0.33469    0.31377  6.25%  30.5  151s
 52870  3959     cutoff   28         0.33469    0.31453  6.02%  30.3  155s
 55392  3805     cutoff   39         0.33469    0.31546  5.75%  30.1  161s
 57670  3645    0.32068   40   10    0.33469    0.31666  5.39%  29.9  165s
 60440  3410    0.33403   35    4    0.33469    0.31792  5.01%  29.7  170s
 62724  3117    0.33339   41    4    0.33469    0.31898  4.69%  29.5  176s
 65394  2670     cutoff   35         0.33469    0.32025  4.32%  29.3  180s
 68891  2065    0.33238   33   10    0.33469    0.32258  3.62%  29.0  186s
 71315  1334    0.32514   34   10    0.33469    0.32474  2.97%  28.8  190s

Cutting planes:
  Implied bound: 283
  MIR: 163
  Flow cover: 522
  RLT: 72
  Relax-and-lift: 138
  BQP: 1

Explored 74457 nodes (2119210 simplex iterations) in 192.46 seconds (233.72 work units)
Thread count was 4 (of 256 available processors)

Solution count 7: 0.33469 0.33469 0.33481 ... 0.492938

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

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

        Job resource usage summary 

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

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