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.540643 seconds.
    local lower bound updated to 0.251619
    local upper bound updated to 0.459989

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.562154
    local lower bound updated to 0.550983

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.556991
    local lower bound updated to 0.555204

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 8.07899 seconds.
    local lower bound updated to 0.382175
    local upper bound updated to 0.502341

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.711239 seconds.
    local lower bound updated to 0.42785
    local upper bound updated to 0.500831

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 8.63501 seconds.
    local lower bound updated to 0.493636
    local upper bound updated to 0.498968

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 3.64161 seconds.
    local lower bound updated to 0.493636
    local upper bound updated to 0.498934

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 9.97901 seconds.
    local lower bound updated to 0.495385
    local upper bound updated to 0.4963

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 7.99421 seconds.
    local lower bound updated to 0.495385
    local upper bound updated to 0.496167

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 10.0806 seconds.
    local lower bound updated to 0.496166
    local upper bound updated to 0.496167
The Primal step takes 49.1359 (S)
lower bound updated to 0.496166

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.537686
    local lower bound updated to 0.504211

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.518974
    local lower bound updated to 0.510159

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.512316
    local lower bound updated to 0.510364

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 14.0222 seconds.
    local lower bound updated to 0.429923
    local upper bound updated to 0.505145

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 9.43607 seconds.
    local lower bound updated to 0.480039
    local upper bound updated to 0.501807

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 14.8459 seconds.
    local lower bound updated to 0.493542
    local upper bound updated to 0.500783

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 20.5373 seconds.
    local lower bound updated to 0.493542
    local upper bound updated to 0.500028

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 18.9613 seconds.
    local lower bound updated to 0.498334
    local upper bound updated to 0.499925

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 18.8818 seconds.
    local lower bound updated to 0.498334
    local upper bound updated to 0.499781

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 14.7964 seconds.
    local lower bound updated to 0.498334
    local upper bound updated to 0.4997

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 24.0092 seconds.
    local lower bound updated to 0.49903
    local upper bound updated to 0.499635

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 21.3579 seconds.
    local lower bound updated to 0.49952
    local upper bound updated to 0.499626

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 22.2732 seconds.
    local lower bound updated to 0.499624
    local upper bound updated to 0.499625
The Primal step takes 179.153 (S)
lower bound updated to 0.499624

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.519173
    local lower bound updated to 0.499625

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.505425
    local lower bound updated to 0.501017

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.505425
    local lower bound updated to 0.501062

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.505425
    local lower bound updated to 0.501105

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 41.826 seconds.
    local lower bound updated to 0.449909
    local upper bound updated to 0.501091

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 29.8679 seconds.
    local lower bound updated to 0.493958
    local upper bound updated to 0.500578

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 46.5115 seconds.
    local lower bound updated to 0.498083
    local upper bound updated to 0.500467

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 33.8906 seconds.
    local lower bound updated to 0.500019
    local upper bound updated to 0.500437

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 37.5852 seconds.
    local lower bound updated to 0.500019
    local upper bound updated to 0.500432

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 35.394 seconds.
    local lower bound updated to 0.500272
    local upper bound updated to 0.500413

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 36.548 seconds.
    local lower bound updated to 0.500313
    local upper bound updated to 0.500409

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 38.8075 seconds.
    local lower bound updated to 0.500372
    local upper bound updated to 0.500405

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 36.5485 seconds.
    local lower bound updated to 0.500374
    local upper bound updated to 0.500403

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 39.9629 seconds.
    local lower bound updated to 0.500386
    local upper bound updated to 0.500402

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 36.0562 seconds.
    local lower bound updated to 0.500402
    local upper bound updated to 0.500402
The Primal step takes 413.047 (S)
lower bound updated to 0.500402

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.514282
    local lower bound updated to 0.500402

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 43.9095 seconds.
    local lower bound updated to 0.449909
    local upper bound updated to 0.500402

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 34.6005 seconds.
    local lower bound updated to 0.500402
    local upper bound updated to 0.500402
The time obtain the optimal randomized assortment is 727.878 (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_100.000000_100216.log"
The computation time limit for a lower bound is 85270
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 24 physical cores, 48 logical processors, using up to 4 threads
Optimize a model with 1680 rows, 1358 columns and 5415 nonzeros
Model fingerprint: 0xd8952f25
Variable types: 1301 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [1e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 424 rows and 790 columns
Presolve time: 0.01s
Presolved: 1256 rows, 568 columns, 4801 nonzeros
Variable types: 512 continuous, 56 integer (56 binary)
Found heuristic solution: objective 0.5855828
Found heuristic solution: objective 0.5388698

Root relaxation: objective 2.726045e-01, 174 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.27260    0   21    0.53887    0.27260  49.4%     -    0s
     0     0    0.30037    0   29    0.53887    0.30037  44.3%     -    0s
H    0     0                       0.5388582    0.30037  44.3%     -    0s
     0     0    0.36532    0   29    0.53886    0.36532  32.2%     -    0s
H    0     0                       0.5078991    0.36532  28.1%     -    0s
     0     0    0.37933    0   28    0.50790    0.37933  25.3%     -    0s
H    0     0                       0.5077117    0.37933  25.3%     -    0s
H    0     0                       0.5025529    0.38871  22.7%     -    0s
     0     0    0.38871    0   25    0.50255    0.38871  22.7%     -    0s
     0     0    0.39022    0   29    0.50255    0.39022  22.4%     -    0s
H    0     0                       0.5023796    0.39406  21.6%     -    0s
     0     0    0.39406    0   24    0.50238    0.39406  21.6%     -    0s
     0     0    0.39472    0   25    0.50238    0.39472  21.4%     -    0s
     0     0    0.39806    0   26    0.50238    0.39806  20.8%     -    0s
H    0     0                       0.5004018    0.39806  20.5%     -    1s
     0     2    0.39806    0   26    0.50040    0.39806  20.5%     -    1s
  2471   770    0.46229   12   17    0.50040    0.45109  9.86%  15.0    5s
H 2601   770                       0.5004017    0.45189  9.69%  15.0    5s
  3296  1016    0.49236   23   12    0.50040    0.45364  9.34%  15.9   10s
  5273   867    0.47918   16   16    0.50040    0.46421  7.23%  17.4   15s
  7078   856    0.49715   25   10    0.50040    0.47032  6.01%  17.4   20s
* 8604   935              29       0.5004017    0.47388  5.30%  17.4   22s
H 8925   942                       0.5004017    0.47472  5.13%  17.3   22s
  9780  1001    0.49931   27    2    0.50040    0.47570  4.94%  17.4   25s
 13917   849    0.49636   27    8    0.50040    0.48407  3.26%  16.9   30s

Cutting planes:
  Implied bound: 139
  MIR: 87
  Flow cover: 267
  RLT: 48
  Relax-and-lift: 45

Explored 18972 nodes (298719 simplex iterations) in 33.66 seconds (26.38 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.500402 0.500402 0.500402 ... 0.585583

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

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

        Job resource usage summary 

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

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