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.52256 seconds.
    local lower bound updated to 0.314156
    local upper bound updated to 0.49946

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.647383
    local lower bound updated to 0.582995

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 2.19734 seconds.
    local lower bound updated to 0.382114
    local upper bound updated to 0.485166

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.82609 seconds.
    local lower bound updated to 0.473128
    local upper bound updated to 0.480376

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.57863 seconds.
    local lower bound updated to 0.473128
    local upper bound updated to 0.473966

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.39222 seconds.
    local lower bound updated to 0.473462
    local upper bound updated to 0.473948

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 1.0652 seconds.
    local lower bound updated to 0.473599
    local upper bound updated to 0.473908

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 2.19994 seconds.
    local lower bound updated to 0.473599
    local upper bound updated to 0.473701

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.515647
    local lower bound updated to 0.473701

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.496503
    local lower bound updated to 0.487709

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.496503
    local lower bound updated to 0.491237

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 9.19854 seconds.
    local lower bound updated to 0.421714
    local upper bound updated to 0.489557

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.5017 seconds.
    local lower bound updated to 0.470381
    local upper bound updated to 0.487122

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 7.25208 seconds.
    local lower bound updated to 0.480965
    local upper bound updated to 0.485062

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 9.20459 seconds.
    local lower bound updated to 0.480965
    local upper bound updated to 0.484607

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 10.4075 seconds.
    local lower bound updated to 0.481902
    local upper bound updated to 0.483878

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 9.06188 seconds.
    local lower bound updated to 0.483475
    local upper bound updated to 0.483747

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.517306
    local lower bound updated to 0.483747

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.495562
    local lower bound updated to 0.486879

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.491387
    local lower bound updated to 0.486879

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 14.1715 seconds.
    local lower bound updated to 0.437662
    local upper bound updated to 0.486879

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 4.71648 seconds.
    local lower bound updated to 0.481031
    local upper bound updated to 0.48674

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 9.8333 seconds.
    local lower bound updated to 0.483081
    local upper bound updated to 0.486287

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 19.4981 seconds.
    local lower bound updated to 0.485514
    local upper bound updated to 0.486186

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 20.1584 seconds.
    local lower bound updated to 0.485514
    local upper bound updated to 0.486113

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 17.2882 seconds.
    local lower bound updated to 0.485514
    local upper bound updated to 0.48602

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 19.0004 seconds.
    local lower bound updated to 0.485779
    local upper bound updated to 0.48594

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 19.9292 seconds.
    local lower bound updated to 0.485779
    local upper bound updated to 0.485938

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.510976
    local lower bound updated to 0.485938

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.488791
    local lower bound updated to 0.485938

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 13.2301 seconds.
    local lower bound updated to 0.437407
    local upper bound updated to 0.485938

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 22.3839 seconds.
    local lower bound updated to 0.485938
    local upper bound updated to 0.485938
The time obtain the optimal randomized assortment is 262.651 (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_63916.log"
The computation time limit for a lower bound is 85735
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 5370 nonzeros
Model fingerprint: 0x3465ed8d
Variable types: 1301 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [7e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 466 rows and 808 columns
Presolve time: 0.01s
Presolved: 1214 rows, 550 columns, 4632 nonzeros
Variable types: 496 continuous, 54 integer (54 binary)
Found heuristic solution: objective 0.5699369
Found heuristic solution: objective 0.5459230

Root relaxation: objective 3.804141e-01, 136 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.38041    0   16    0.54592    0.38041  30.3%     -    0s
H    0     0                       0.5146179    0.38041  26.1%     -    0s
     0     0    0.39039    0   25    0.51462    0.39039  24.1%     -    0s
     0     0    0.39039    0   25    0.51462    0.39039  24.1%     -    0s
H    0     0                       0.5075229    0.39039  23.1%     -    0s
     0     0    0.42412    0   23    0.50752    0.42412  16.4%     -    0s
H    0     0                       0.4975386    0.42412  14.8%     -    0s
     0     0    0.42414    0   24    0.49754    0.42414  14.8%     -    0s
     0     0    0.42944    0   23    0.49754    0.42944  13.7%     -    0s
H    0     0                       0.4949083    0.42944  13.2%     -    0s
H    0     0                       0.4937665    0.43051  12.8%     -    0s
     0     0    0.43051    0   22    0.49377    0.43051  12.8%     -    0s
     0     0    0.43645    0   22    0.49377    0.43645  11.6%     -    0s
H    0     0                       0.4889145    0.43645  10.7%     -    0s
     0     0    0.43692    0   22    0.48891    0.43692  10.6%     -    0s
     0     0    0.43863    0   23    0.48891    0.43863  10.3%     -    0s
H    0     0                       0.4859384    0.43863  9.73%     -    1s
     0     2    0.43863    0   23    0.48594    0.43863  9.73%     -    1s
  2707   673    0.46491   11   12    0.48594    0.46305  4.71%  15.8    5s
  4494   863     cutoff   23         0.48594    0.46639  4.02%  16.2   10s
  6685   404     cutoff   27         0.48594    0.47063  3.15%  17.6   15s
  9016   285     cutoff   23         0.48594    0.47605  2.03%  18.2   20s

Cutting planes:
  Implied bound: 133
  MIR: 85
  Flow cover: 220
  RLT: 34
  Relax-and-lift: 69

Explored 10302 nodes (188095 simplex iterations) in 22.41 seconds (18.15 work units)
Thread count was 4 (of 48 available processors)

Solution count 9: 0.485938 0.488915 0.493766 ... 0.569937

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

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

        Job resource usage summary 

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

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