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.04558 seconds.
    local lower bound updated to 0.238709
    local upper bound updated to 0.458637

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.721223
    local lower bound updated to 0.719633

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.04095 seconds.
    local lower bound updated to 0.27741
    local upper bound updated to 0.479465

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 9.88083 seconds.
    local lower bound updated to 0.474291
    local upper bound updated to 0.476895

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 7.20379 seconds.
    local lower bound updated to 0.474291
    local upper bound updated to 0.474923

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.601606
    local lower bound updated to 0.502146

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.532678
    local lower bound updated to 0.509558

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.532678
    local lower bound updated to 0.520457

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 7.92422 seconds.
    local lower bound updated to 0.382345
    local upper bound updated to 0.509848

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.799801 seconds.
    local lower bound updated to 0.382345
    local upper bound updated to 0.499552

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 34.1101 seconds.
    local lower bound updated to 0.484257
    local upper bound updated to 0.493373

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 34.6346 seconds.
    local lower bound updated to 0.484257
    local upper bound updated to 0.49322

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 49.3124 seconds.
    local lower bound updated to 0.493202
    local upper bound updated to 0.49322

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 54.1252 seconds.
    local lower bound updated to 0.49322
    local upper bound updated to 0.49322
The Primal step takes 180.929 (S)
lower bound updated to 0.49322

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.594092
    local lower bound updated to 0.49322

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.499936
    local lower bound updated to 0.496657

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.499936
    local lower bound updated to 0.497414

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.497697
    local lower bound updated to 0.497462

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 18.6725 seconds.
    local lower bound updated to 0.418154
    local upper bound updated to 0.497462

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 15.9578 seconds.
    local lower bound updated to 0.473665
    local upper bound updated to 0.496401

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 30.4573 seconds.
    local lower bound updated to 0.481349
    local upper bound updated to 0.496319

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 40.0525 seconds.
    local lower bound updated to 0.482669
    local upper bound updated to 0.495685

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 99.873 seconds.
    local lower bound updated to 0.488136
    local upper bound updated to 0.495243

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 96.398 seconds.
    local lower bound updated to 0.488136
    local upper bound updated to 0.494491

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 89.2441 seconds.
    local lower bound updated to 0.494125
    local upper bound updated to 0.494225

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 91.8634 seconds.
    local lower bound updated to 0.494225
    local upper bound updated to 0.494225
The Primal step takes 482.562 (S)
lower bound updated to 0.494225

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.55958
    local lower bound updated to 0.494225

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.503102
    local lower bound updated to 0.49436

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.501285
    local lower bound updated to 0.494744

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.496052
    local lower bound updated to 0.494744

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 75.2817 seconds.
    local lower bound updated to 0.4331
    local upper bound updated to 0.494744

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.027 seconds.
    local lower bound updated to 0.49315
    local upper bound updated to 0.494643

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 156.643 seconds.
    local lower bound updated to 0.494292
    local upper bound updated to 0.494632

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 158.343 seconds.
    local lower bound updated to 0.494329
    local upper bound updated to 0.494453

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 130.767 seconds.
    local lower bound updated to 0.494407
    local upper bound updated to 0.494451

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 133.79 seconds.
    local lower bound updated to 0.494437
    local upper bound updated to 0.49445

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.53856
    local lower bound updated to 0.49445

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 80.5895 seconds.
    local lower bound updated to 0.4331
    local upper bound updated to 0.49445

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 150.589 seconds.
    local lower bound updated to 0.49445
    local upper bound updated to 0.49445
The time obtain the optimal randomized assortment is 1943.77 (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_200.000000_207218.log"
The computation time limit for a lower bound is 84042
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 20 physical cores, 40 logical processors, using up to 4 threads
Optimize a model with 2604 rows, 1990 columns and 8528 nonzeros
Model fingerprint: 0x7620f580
Variable types: 1925 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [3e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 586 rows and 1072 columns
Presolve time: 0.04s
Presolved: 2018 rows, 918 columns, 7672 nonzeros
Variable types: 855 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.7181259
Found heuristic solution: objective 0.5628825

Root relaxation: objective 3.184357e-01, 266 iterations, 0.01 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.31844    0   21    0.56288    0.31844  43.4%     -    0s
H    0     0                       0.5404847    0.31844  41.1%     -    0s
H    0     0                       0.5403413    0.31844  41.1%     -    0s
H    0     0                       0.5378599    0.32247  40.0%     -    0s
     0     0    0.32247    0   25    0.53786    0.32247  40.0%     -    0s
H    0     0                       0.5355010    0.32247  39.8%     -    0s
     0     0    0.35853    0   29    0.53550    0.35853  33.0%     -    0s
H    0     0                       0.5106112    0.35853  29.8%     -    0s
     0     0    0.35939    0   29    0.51061    0.35939  29.6%     -    0s
H    0     0                       0.4981368    0.37205  25.3%     -    0s
     0     0    0.37205    0   34    0.49814    0.37205  25.3%     -    0s
     0     0    0.37858    0   31    0.49814    0.37858  24.0%     -    0s
     0     0    0.39143    0   30    0.49814    0.39143  21.4%     -    0s
H    0     0                       0.4955741    0.39143  21.0%     -    0s
     0     0    0.39234    0   31    0.49557    0.39234  20.8%     -    0s
     0     0    0.39396    0   31    0.49557    0.39396  20.5%     -    1s
H    0     0                       0.4944502    0.39396  20.3%     -    3s
     0     2    0.39396    0   31    0.49445    0.39396  20.3%     -    3s
   276   160    0.42956    8   18    0.49445    0.40593  17.9%  40.9    5s
   996   432    0.47660    9   18    0.49445    0.42423  14.2%  32.9   11s
  2079   720    0.49047   13   10    0.49445    0.43292  12.4%  31.3   15s
  2397   796    0.48751   19    8    0.49445    0.43507  12.0%  30.6   20s
  3462  1009    0.46293   14   31    0.49445    0.43848  11.3%  28.8   25s
  3982  1077     cutoff   30         0.49445    0.43848  11.3%  30.8   30s
  4896  1049    0.46527   25   19    0.49445    0.43864  11.3%  31.3   35s
* 5029   976              34       0.4944502    0.43992  11.0%  31.2   35s
  5577   957    0.45550   26   20    0.49445    0.44239  10.5%  31.3   40s
  6092   914    0.45609   27   19    0.49445    0.44499  10.0%  31.5   45s
  6756   987    0.45479   22   20    0.49445    0.44677  9.64%  31.3   50s
  8063  1201    0.47731   28   16    0.49445    0.45025  8.94%  31.3   55s
  8818  1301    0.46733   23   14    0.49445    0.45181  8.62%  31.2   61s
  9495  1369     cutoff   30         0.49445    0.45294  8.40%  31.1   65s
 10201  1463    0.46960   26   12    0.49445    0.45449  8.08%  31.1   71s
 11106  1567    0.47893   27   18    0.49445    0.45562  7.85%  31.0   75s
 12363  1638    0.47693   28   17    0.49445    0.45757  7.46%  31.1   82s
 13981  1696    0.48692   30    8    0.49445    0.45923  7.12%  31.1   87s
 14815  1733    0.49357   29   14    0.49445    0.46119  6.73%  31.1   91s
 15754  1749    0.48629   26   14    0.49445    0.46257  6.45%  31.0   96s
 16895  1770     cutoff   28         0.49445    0.46385  6.19%  31.0  102s
 17678  1761     cutoff   33         0.49445    0.46476  6.01%  31.0  106s
 18290  1745     cutoff   29         0.49445    0.46537  5.88%  31.0  111s
 18876  1727    0.48925   27   14    0.49445    0.46596  5.76%  31.0  115s
 20630  1671     cutoff   28         0.49445    0.46782  5.39%  31.0  120s
 22576  1567    0.48964   30   12    0.49445    0.46999  4.95%  30.9  125s
 23691  1500    0.47972   33   10    0.49445    0.47093  4.76%  30.9  130s
H24820  1455                       0.4944502    0.47196  4.55%  30.7  134s
 24839  1407    0.49427   31   10    0.49445    0.47215  4.51%  30.7  136s
H26089  1285                       0.4944502    0.47327  4.28%  30.7  139s
 26500  1181    0.49070   30   12    0.49445    0.47372  4.19%  30.6  140s
 28345   823     cutoff   27         0.49445    0.47721  3.49%  30.6  145s
 30497   441     cutoff   31         0.49445    0.48227  2.46%  30.3  151s

Cutting planes:
  Implied bound: 259
  MIR: 146
  Flow cover: 401
  RLT: 81
  Relax-and-lift: 98

Explored 31282 nodes (942943 simplex iterations) in 153.45 seconds (114.18 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.49445 0.49445 0.49445 ... 0.540485

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

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

        Job resource usage summary 

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

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