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.657558 seconds.
    local lower bound updated to 0.326014
    local upper bound updated to 0.618699

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.717785
    local lower bound updated to 0.689253

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 3.98864 seconds.
    local lower bound updated to 0.425658
    local upper bound updated to 0.621087

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.78223 seconds.
    local lower bound updated to 0.597436
    local upper bound updated to 0.610862

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 2.17383 seconds.
    local lower bound updated to 0.603404
    local upper bound updated to 0.605643

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.98936 seconds.
    local lower bound updated to 0.604175
    local upper bound updated to 0.605053

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.666901
    local lower bound updated to 0.628052

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.645932
    local lower bound updated to 0.628771

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.633929
    local lower bound updated to 0.630607

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.632447
    local lower bound updated to 0.63166

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.63166
    local lower bound updated to 0.63166
The Dual step takes 0.493344 (S)
upper bound updated to 0.63166
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 16.6031 seconds.
    local lower bound updated to 0.500306
    local upper bound updated to 0.626809

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 17.0008 seconds.
    local lower bound updated to 0.618528
    local upper bound updated to 0.622529

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 13.0775 seconds.
    local lower bound updated to 0.619138
    local upper bound updated to 0.620444

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 15.4274 seconds.
    local lower bound updated to 0.619179
    local upper bound updated to 0.620335

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 15.2644 seconds.
    local lower bound updated to 0.619898
    local upper bound updated to 0.62018

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 15.4186 seconds.
    local lower bound updated to 0.619898
    local upper bound updated to 0.620054

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 17.0065 seconds.
    local lower bound updated to 0.619898
    local upper bound updated to 0.620044

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.641757
    local lower bound updated to 0.620044

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.641757
    local lower bound updated to 0.620313

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 35.2491 seconds.
    local lower bound updated to 0.532311
    local upper bound updated to 0.620313

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.64055
    local lower bound updated to 0.620313

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 34.5021 seconds.
    local lower bound updated to 0.532311
    local upper bound updated to 0.620313

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 18.4127 seconds.
    local lower bound updated to 0.620313
    local upper bound updated to 0.620313
The time obtain the optimal randomized assortment is 250.506 (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_15_4_60.000000_66216.log"
The computation time limit for a lower bound is 85745
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 1520 rows, 1228 columns and 4827 nonzeros
Model fingerprint: 0xa7c82e76
Variable types: 1171 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [1e-05, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [7e-01, 1e+00]
Presolve removed 423 rows and 727 columns
Presolve time: 0.01s
Presolved: 1097 rows, 501 columns, 4200 nonzeros
Variable types: 446 continuous, 55 integer (55 binary)
Found heuristic solution: objective 0.7657207
Found heuristic solution: objective 0.6769595

Root relaxation: objective 4.080906e-01, 170 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.40809    0   18    0.67696    0.40809  39.7%     -    0s
H    0     0                       0.6651432    0.40809  38.6%     -    0s
H    0     0                       0.6469035    0.42438  34.4%     -    0s
     0     0    0.42438    0   22    0.64690    0.42438  34.4%     -    0s
H    0     0                       0.6286089    0.42438  32.5%     -    0s
     0     0    0.48257    0   25    0.62861    0.48257  23.2%     -    0s
     0     0    0.48573    0   24    0.62861    0.48573  22.7%     -    0s
     0     0    0.51631    0   25    0.62861    0.51631  17.9%     -    0s
     0     0    0.51697    0   25    0.62861    0.51697  17.8%     -    0s
     0     0    0.52281    0   24    0.62861    0.52281  16.8%     -    0s
     0     0    0.52416    0   25    0.62861    0.52416  16.6%     -    0s
     0     0    0.52538    0   24    0.62861    0.52538  16.4%     -    0s
H    0     0                       0.6203127    0.52538  15.3%     -    1s
     0     2    0.52538    0   24    0.62031    0.52538  15.3%     -    1s
  2487   571     cutoff   16         0.62031    0.57673  7.03%  17.4    5s
  4945   844    0.58637   16   16    0.62031    0.58637  5.47%  17.4   10s
  7692   306    0.60584   23   10    0.62031    0.59620  3.89%  19.0   15s

Cutting planes:
  Implied bound: 109
  MIR: 78
  Flow cover: 185
  RLT: 42
  Relax-and-lift: 34

Explored 10087 nodes (191944 simplex iterations) in 18.87 seconds (11.14 work units)
Thread count was 4 (of 40 available processors)

Solution count 6: 0.620313 0.628609 0.646904 ... 0.765721

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

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

        Job resource usage summary 

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

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