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.542941 seconds.
    local lower bound updated to 0.223966
    local upper bound updated to 0.431679

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.630367
    local lower bound updated to 0.628862

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 10.2992 seconds.
    local lower bound updated to 0.283073
    local upper bound updated to 0.443159

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.92037 seconds.
    local lower bound updated to 0.431299
    local upper bound updated to 0.433947

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 3.12255 seconds.
    local lower bound updated to 0.431299
    local upper bound updated to 0.431905

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 3.58633 seconds.
    local lower bound updated to 0.431854
    local upper bound updated to 0.431868

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.552347
    local lower bound updated to 0.531125

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.546197
    local lower bound updated to 0.531335

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 43.4131 seconds.
    local lower bound updated to 0.358327
    local upper bound updated to 0.474106

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 25.1841 seconds.
    local lower bound updated to 0.415076
    local upper bound updated to 0.464728

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 36.3303 seconds.
    local lower bound updated to 0.434547
    local upper bound updated to 0.459125

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 11.6033 seconds.
    local lower bound updated to 0.442164
    local upper bound updated to 0.451964

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 28.0252 seconds.
    local lower bound updated to 0.442164
    local upper bound updated to 0.448585

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 38.8472 seconds.
    local lower bound updated to 0.445696
    local upper bound updated to 0.448322

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 36.8487 seconds.
    local lower bound updated to 0.445696
    local upper bound updated to 0.447732

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 37.3952 seconds.
    local lower bound updated to 0.447055
    local upper bound updated to 0.447413

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 33.3418 seconds.
    local lower bound updated to 0.44729
    local upper bound updated to 0.44738

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 37.6392 seconds.
    local lower bound updated to 0.44738
    local upper bound updated to 0.44738
The Primal step takes 328.674 (S)
lower bound updated to 0.44738

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.510108
    local lower bound updated to 0.456523

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.46878
    local lower bound updated to 0.460387

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.46878
    local lower bound updated to 0.462258

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.46878
    local lower bound updated to 0.465197

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.467684
    local lower bound updated to 0.465687

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 157.347 seconds.
    local lower bound updated to 0.414412
    local upper bound updated to 0.461896

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 59.282 seconds.
    local lower bound updated to 0.455662
    local upper bound updated to 0.460844

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 69.9003 seconds.
    local lower bound updated to 0.455662
    local upper bound updated to 0.460629

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 85.578 seconds.
    local lower bound updated to 0.457021
    local upper bound updated to 0.460382

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 53.2576 seconds.
    local lower bound updated to 0.457021
    local upper bound updated to 0.460153

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 54.5326 seconds.
    local lower bound updated to 0.457123
    local upper bound updated to 0.460006

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 76.1841 seconds.
    local lower bound updated to 0.457507
    local upper bound updated to 0.459981

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 63.5084 seconds.
    local lower bound updated to 0.458623
    local upper bound updated to 0.459861

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 76.4516 seconds.
    local lower bound updated to 0.458623
    local upper bound updated to 0.459812

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 80.4272 seconds.
    local lower bound updated to 0.458626
    local upper bound updated to 0.459545

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 83.5338 seconds.
    local lower bound updated to 0.459264
    local upper bound updated to 0.459513

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 88.2591 seconds.
    local lower bound updated to 0.459329
    local upper bound updated to 0.459462

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 71.5325 seconds.
    local lower bound updated to 0.459462
    local upper bound updated to 0.459462
The Primal step takes 1019.9 (S)
lower bound updated to 0.459462

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.497157
    local lower bound updated to 0.459788

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.461902
    local lower bound updated to 0.46022

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.46031
    local lower bound updated to 0.460221

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.031 seconds.
    local lower bound updated to 0.420665
    local upper bound updated to 0.460221

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 83.4392 seconds.
    local lower bound updated to 0.453426
    local upper bound updated to 0.460198

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 136.023 seconds.
    local lower bound updated to 0.458165
    local upper bound updated to 0.459756

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 123.792 seconds.
    local lower bound updated to 0.458665
    local upper bound updated to 0.459703

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 122.166 seconds.
    local lower bound updated to 0.459154
    local upper bound updated to 0.459644

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 113.672 seconds.
    local lower bound updated to 0.459154
    local upper bound updated to 0.45962

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 122.838 seconds.
    local lower bound updated to 0.459154
    local upper bound updated to 0.459592

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 127.497 seconds.
    local lower bound updated to 0.459394
    local upper bound updated to 0.459591

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 104.702 seconds.
    local lower bound updated to 0.459394
    local upper bound updated to 0.459557

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 101.244 seconds.
    local lower bound updated to 0.459557
    local upper bound updated to 0.459557
The Primal step takes 1235.5 (S)
lower bound updated to 0.459557

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.489359
    local lower bound updated to 0.459557

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.461232
    local lower bound updated to 0.459579

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.041 seconds.
    local lower bound updated to 0.423724
    local upper bound updated to 0.459579

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 151.77 seconds.
    local lower bound updated to 0.459551
    local upper bound updated to 0.459569

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 136.599 seconds.
    local lower bound updated to 0.459551
    local upper bound updated to 0.459566

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.487746
    local lower bound updated to 0.459566

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.043 seconds.
    local lower bound updated to 0.424232
    local upper bound updated to 0.459566

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 154.333 seconds.
    local lower bound updated to 0.459566
    local upper bound updated to 0.459566
The time obtain the optimal randomized assortment is 3646.18 (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_208018.log"
The computation time limit for a lower bound is 82343
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 2992 rows, 2286 columns and 9910 nonzeros
Model fingerprint: 0xc2461bf2
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 708 rows and 1254 columns
Presolve time: 0.05s
Presolved: 2284 rows, 1032 columns, 8907 nonzeros
Variable types: 969 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6468986
Found heuristic solution: objective 0.5228387

Root relaxation: objective 2.914418e-01, 322 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.29144    0   29    0.52284    0.29144  44.3%     -    0s
H    0     0                       0.5099641    0.29144  42.9%     -    0s
H    0     0                       0.5052007    0.29832  40.9%     -    0s
     0     0    0.30030    0   29    0.50520    0.30030  40.6%     -    0s
H    0     0                       0.5017311    0.30030  40.1%     -    0s
     0     0    0.34748    0   29    0.50173    0.34748  30.7%     -    0s
     0     0    0.34912    0   29    0.50173    0.34912  30.4%     -    0s
H    0     0                       0.4696863    0.35758  23.9%     -    0s
     0     0    0.35758    0   29    0.46969    0.35758  23.9%     -    0s
     0     0    0.35802    0   29    0.46969    0.35802  23.8%     -    0s
H    0     0                       0.4667185    0.36497  21.8%     -    1s
     0     0    0.36497    0   29    0.46672    0.36497  21.8%     -    1s
H    0     0                       0.4666106    0.36589  21.6%     -    1s
     0     0    0.36589    0   31    0.46661    0.36589  21.6%     -    1s
     0     0    0.36798    0   32    0.46661    0.36798  21.1%     -    1s
H    0     0                       0.4595663    0.36798  19.9%     -    4s
     0     2    0.36798    0   32    0.45957    0.36798  19.9%     -    4s
    11    16    0.41207    4   29    0.45957    0.38443  16.3%   132    5s
   399   179    0.43455   12   14    0.45957    0.39355  14.4%  35.2   10s
   699   284    0.43783    8   14    0.45957    0.39857  13.3%  30.2   15s
  1088   418    0.44863   14   12    0.45957    0.40151  12.6%  29.0   20s
H 1212   451                       0.4595663    0.40274  12.4%  28.4   22s
  1386   520    0.44057   14   14    0.45957    0.40432  12.0%  27.8   25s
  1673   626    0.45579   18   12    0.45957    0.40524  11.8%  27.2   30s
  2058   723    0.43466   12   32    0.45957    0.40795  11.2%  26.4   36s
  2078   736    0.43880   16   31    0.45957    0.40795  11.2%  26.2   40s
H 2587   734                       0.4595663    0.40795  11.2%  28.4   44s
  2896   724    0.44970   27   18    0.45957    0.40885  11.0%  28.6   46s
  3597   702    0.45210   29   16    0.45957    0.41459  9.79%  29.0   50s
  4477   659    0.43855   26   17    0.45957    0.41972  8.67%  29.2   56s
  4965   754    0.45681   27   16    0.45957    0.42181  8.22%  29.3   60s
  5398   819    0.44750   25   19    0.45957    0.42289  7.98%  29.4   65s
  5968   916     cutoff   28         0.45957    0.42437  7.66%  29.7   70s
  6558  1037    0.45744   27   12    0.45957    0.42641  7.21%  29.7   75s
  7543  1135    0.43685   27   19    0.45957    0.42884  6.69%  29.5   81s
  8193  1188    0.45071   30   19    0.45957    0.43029  6.37%  29.5   85s
  9127  1279    0.45152   27   12    0.45957    0.43205  5.99%  29.4   91s
 10535  1338    0.45458   26   14    0.45957    0.43493  5.36%  28.8   96s
H10834  1338                       0.4595662    0.43505  5.33%  28.8   96s
 11501  1356    0.45304   27   14    0.45957    0.43683  4.95%  28.7  102s
H11706  1356                       0.4595662    0.43684  4.95%  28.6  102s
 12234  1372     cutoff   29         0.45957    0.43816  4.66%  28.6  105s
 13316  1329    0.45519   29   12    0.45957    0.43959  4.35%  28.2  110s
 13824  1314    0.45399   27   14    0.45957    0.44118  4.00%  28.1  115s
 15910  1272     cutoff   34         0.45957    0.44446  3.29%  27.5  120s
 17779  1185     cutoff   31         0.45957    0.44656  2.83%  27.0  126s
 19028  1064 infeasible   33         0.45957    0.44772  2.58%  26.6  130s
 20989   893    0.45803   34    8    0.45957    0.45023  2.03%  26.0  135s
 21943   638     cutoff   27         0.45957    0.45141  1.77%  25.8  140s

Cutting planes:
  Implied bound: 223
  MIR: 173
  Flow cover: 516
  RLT: 58
  Relax-and-lift: 52

Explored 24269 nodes (609472 simplex iterations) in 144.77 seconds (78.40 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.459566 0.459566 0.466611 ... 0.646899

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

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

        Job resource usage summary 

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

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