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.45567 seconds.
    local lower bound updated to 0.201837
    local upper bound updated to 0.35384

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.432631
    local lower bound updated to 0.429586

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.432631
    local lower bound updated to 0.432001

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 13.4863 seconds.
    local lower bound updated to 0.263212
    local upper bound updated to 0.367927

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 14.5862 seconds.
    local lower bound updated to 0.351458
    local upper bound updated to 0.35642

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 22.6603 seconds.
    local lower bound updated to 0.351458
    local upper bound updated to 0.355183

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 33.4201 seconds.
    local lower bound updated to 0.351812
    local upper bound updated to 0.3545

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 29.1885 seconds.
    local lower bound updated to 0.35399
    local upper bound updated to 0.354364

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 23.5611 seconds.
    local lower bound updated to 0.35422
    local upper bound updated to 0.354343

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.399075
    local lower bound updated to 0.368522

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.390373
    local lower bound updated to 0.387558

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 30.7612 seconds.
    local lower bound updated to 0.300249
    local upper bound updated to 0.379897

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.91531 seconds.
    local lower bound updated to 0.337054
    local upper bound updated to 0.377678

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 38.1216 seconds.
    local lower bound updated to 0.365085
    local upper bound updated to 0.376026

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 32.1871 seconds.
    local lower bound updated to 0.365559
    local upper bound updated to 0.370044

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 30.414 seconds.
    local lower bound updated to 0.366381
    local upper bound updated to 0.369157

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 39.0443 seconds.
    local lower bound updated to 0.366381
    local upper bound updated to 0.368236

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 39.267 seconds.
    local lower bound updated to 0.366381
    local upper bound updated to 0.368028

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 36.8235 seconds.
    local lower bound updated to 0.367936
    local upper bound updated to 0.368017

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.397306
    local lower bound updated to 0.369203

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.382012
    local lower bound updated to 0.369771

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.380958
    local lower bound updated to 0.373577

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.378593
    local lower bound updated to 0.375036

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 4.83503 seconds.
    local lower bound updated to 0.322723
    local upper bound updated to 0.371744

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.026 seconds.
    local lower bound updated to 0.361916
    local upper bound updated to 0.370119

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0279 seconds.
    local lower bound updated to 0.365946
    local upper bound updated to 0.369988

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.014 seconds.
    local lower bound updated to 0.366774
    local upper bound updated to 0.369676

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0158 seconds.
    local lower bound updated to 0.367814
    local upper bound updated to 0.369359

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0165 seconds.
    local lower bound updated to 0.368851
    local upper bound updated to 0.369341

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0161 seconds.
    local lower bound updated to 0.369336
    local upper bound updated to 0.36934
The Primal step takes 424.997 (S)
lower bound updated to 0.369336

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.392523
    local lower bound updated to 0.372176

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0221 seconds.
    local lower bound updated to 0.32594
    local upper bound updated to 0.372176

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0199 seconds.
    local lower bound updated to 0.37172
    local upper bound updated to 0.372152

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0251 seconds.
    local lower bound updated to 0.372132
    local upper bound updated to 0.37215

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.387189
    local lower bound updated to 0.37215

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.32594
    local upper bound updated to 0.37215

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0256 seconds.
    local lower bound updated to 0.37215
    local upper bound updated to 0.37215
The time obtain the optimal randomized assortment is 1273.59 (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_19_4_60.000000_68220.log"
The computation time limit for a lower bound is 84718
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 2592 rows, 1900 columns and 8836 nonzeros
Model fingerprint: 0xff39b054
Variable types: 1827 continuous, 73 integer (73 binary)
Coefficient statistics:
  Matrix range     [1e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 553 rows and 943 columns
Presolve time: 0.02s
Presolved: 2039 rows, 957 columns, 8159 nonzeros
Variable types: 886 continuous, 71 integer (71 binary)
Found heuristic solution: objective 0.5241378
Found heuristic solution: objective 0.5148766
Found heuristic solution: objective 0.4493866

Root relaxation: objective 2.557886e-01, 271 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.25579    0   18    0.44939    0.25579  43.1%     -    0s
H    0     0                       0.4457579    0.25579  42.6%     -    0s
H    0     0                       0.4269968    0.25915  39.3%     -    0s
H    0     0                       0.4212426    0.25915  38.5%     -    0s
     0     0    0.25915    0   26    0.42124    0.25915  38.5%     -    0s
H    0     0                       0.4121500    0.25915  37.1%     -    0s
     0     0    0.25915    0   26    0.41215    0.25915  37.1%     -    0s
     0     0    0.28168    0   36    0.41215    0.28168  31.7%     -    0s
     0     0    0.28185    0   36    0.41215    0.28185  31.6%     -    0s
     0     0    0.29175    0   37    0.41215    0.29175  29.2%     -    0s
H    0     0                       0.3991344    0.29175  26.9%     -    0s
     0     0    0.29347    0   34    0.39913    0.29347  26.5%     -    0s
H    0     0                       0.3976274    0.30296  23.8%     -    0s
     0     0    0.30296    0   37    0.39763    0.30296  23.8%     -    0s
H    0     0                       0.3958443    0.30296  23.5%     -    0s
     0     0    0.30387    0   36    0.39584    0.30387  23.2%     -    0s
     0     0    0.30474    0   35    0.39584    0.30474  23.0%     -    0s
H    0     0                       0.3831438    0.30474  20.5%     -    0s
H    0     0                       0.3741641    0.30474  18.6%     -    2s
     0     2    0.30474    0   35    0.37416    0.30474  18.6%     -    2s
H  600   334                       0.3721500    0.31761  14.7%  24.2    3s
H  778   368                       0.3721500    0.31853  14.4%  24.1    3s
  1273   532    0.33158    9   26    0.37215    0.32211  13.4%  23.7    5s
  2496   876    0.33139   18   24    0.37215    0.32708  12.1%  25.6   10s
  3131   870    0.36095   25   18    0.37215    0.32890  11.6%  25.7   15s
  4378   855    0.35712   21   18    0.37215    0.33500  10.0%  25.7   21s
  5080   920    0.35465   22   18    0.37215    0.33667  9.53%  25.7   25s
  6086  1134    0.35281   23   18    0.37215    0.33904  8.90%  25.9   30s
  7226  1367     cutoff   24         0.37215    0.34097  8.38%  25.7   35s
  8399  1509    0.36870   24   16    0.37215    0.34335  7.74%  25.8   40s
  9480  1629 infeasible   27         0.37215    0.34415  7.52%  25.8   46s
*11081  1785              32       0.3721500    0.34595  7.04%  25.5   49s
 11224  1834     cutoff   29         0.37215    0.34639  6.92%  25.6   51s
 12617  1961    0.36074   23   24    0.37215    0.34779  6.55%  25.6   55s
 15170  2096     cutoff   25         0.37215    0.34960  6.06%  25.6   61s
 17132  2146    0.36933   23   26    0.37215    0.35105  5.67%  25.6   65s
 19209  2141    0.36421   21   26    0.37215    0.35263  5.25%  25.6   70s
 21920  2124    0.35894   25   16    0.37215    0.35436  4.78%  25.4   75s
 24598  2070    0.36458   26   22    0.37215    0.35593  4.36%  25.1   80s
 26338  1980     cutoff   28         0.37215    0.35710  4.04%  25.0   85s
 28391  1780     cutoff   26         0.37215    0.35823  3.74%  24.8   91s
 30407  1633     cutoff   25         0.37215    0.35974  3.33%  24.6   95s
 32801  1333    0.37179   25   17    0.37215    0.36114  2.96%  24.2  100s
 36575   830    0.36895   30   16    0.37215    0.36398  2.20%  23.7  105s
 38632   146     cutoff   26         0.37215    0.36700  1.38%  23.3  110s

Cutting planes:
  Implied bound: 226
  MIR: 149
  Flow cover: 373
  RLT: 45
  Relax-and-lift: 92

Explored 39303 nodes (913791 simplex iterations) in 110.54 seconds (121.23 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.37215 0.37215 0.37215 ... 0.421243

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

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

        Job resource usage summary 

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

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