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.52628 seconds.
    local lower bound updated to 0.255322
    local upper bound updated to 0.490965

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.510647
    local lower bound updated to 0.504962

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.510647
    local lower bound updated to 0.510352

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 4.22093 seconds.
    local lower bound updated to 0.369725
    local upper bound updated to 0.487468

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.49141 seconds.
    local lower bound updated to 0.477489
    local upper bound updated to 0.482146

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.14388 seconds.
    local lower bound updated to 0.477489
    local upper bound updated to 0.481622

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 2.6985 seconds.
    local lower bound updated to 0.477489
    local upper bound updated to 0.480888

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 1.75598 seconds.
    local lower bound updated to 0.480614
    local upper bound updated to 0.480716

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.522409
    local lower bound updated to 0.498542

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.521956
    local lower bound updated to 0.50472

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.509218
    local lower bound updated to 0.504773

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 14.8138 seconds.
    local lower bound updated to 0.414987
    local upper bound updated to 0.49708

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.18186 seconds.
    local lower bound updated to 0.438445
    local upper bound updated to 0.491788

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 17.097 seconds.
    local lower bound updated to 0.47957
    local upper bound updated to 0.491644

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 30.1988 seconds.
    local lower bound updated to 0.487137
    local upper bound updated to 0.490988

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 9.59038 seconds.
    local lower bound updated to 0.487137
    local upper bound updated to 0.490272

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 14.7665 seconds.
    local lower bound updated to 0.487137
    local upper bound updated to 0.489707

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 13.0393 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.48945

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 6.81066 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.489138

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 13.8117 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.48877

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 14.7569 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.488616

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 15.8583 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.488479

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 11.7667 seconds.
    local lower bound updated to 0.487945
    local upper bound updated to 0.488478

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 19.5758 seconds.
    local lower bound updated to 0.488022
    local upper bound updated to 0.488478

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 18.7696 seconds.
    local lower bound updated to 0.488255
    local upper bound updated to 0.488477

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 18.3936 seconds.
    local lower bound updated to 0.488471
    local upper bound updated to 0.488477
The Primal step takes 221.503 (S)
lower bound updated to 0.488471

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.498135
    local lower bound updated to 0.488477

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.498135
    local lower bound updated to 0.488966

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.492904
    local lower bound updated to 0.489183

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 22.7283 seconds.
    local lower bound updated to 0.423143
    local upper bound updated to 0.489183

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 12.5989 seconds.
    local lower bound updated to 0.489116
    local upper bound updated to 0.489174

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 13.733 seconds.
    local lower bound updated to 0.489174
    local upper bound updated to 0.489174
The Primal step takes 49.078 (S)
lower bound updated to 0.489174

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.499498
    local lower bound updated to 0.489174

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 21.5676 seconds.
    local lower bound updated to 0.423143
    local upper bound updated to 0.489174

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 11.3546 seconds.
    local lower bound updated to 0.489174
    local upper bound updated to 0.489174
The time obtain the optimal randomized assortment is 333.828 (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_103216.log"
The computation time limit for a lower bound is 85662
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 1520 rows, 1228 columns and 4886 nonzeros
Model fingerprint: 0x073cb5a4
Variable types: 1171 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        [5e-01, 1e+00]
Presolve removed 432 rows and 726 columns
Presolve time: 0.02s
Presolved: 1088 rows, 502 columns, 4219 nonzeros
Variable types: 449 continuous, 53 integer (53 binary)
Found heuristic solution: objective 0.5901690
Found heuristic solution: objective 0.5881626

Root relaxation: objective 2.918798e-01, 130 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.29188    0   21    0.58816    0.29188  50.4%     -    0s
H    0     0                       0.5373092    0.29188  45.7%     -    0s
     0     0    0.31354    0   24    0.53731    0.31354  41.6%     -    0s
H    0     0                       0.5355524    0.31354  41.5%     -    0s
H    0     0                       0.5195677    0.31354  39.7%     -    0s
     0     0    0.35944    0   26    0.51957    0.35944  30.8%     -    0s
H    0     0                       0.5001204    0.35944  28.1%     -    0s
     0     0    0.35969    0   26    0.50012    0.35969  28.1%     -    0s
     0     0    0.37937    0   25    0.50012    0.37937  24.1%     -    0s
     0     0    0.37985    0   26    0.50012    0.37985  24.0%     -    0s
     0     0    0.39650    0   27    0.50012    0.39650  20.7%     -    0s
     0     0    0.39831    0   27    0.50012    0.39831  20.4%     -    0s
     0     0    0.40137    0   20    0.50012    0.40137  19.7%     -    0s
H    0     0                       0.4973633    0.40137  19.3%     -    1s
     0     2    0.40137    0   20    0.49736    0.40137  19.3%     -    1s
H    3     8                       0.4929695    0.40853  17.1%  66.3    1s
*  118    82              16       0.4891739    0.42373  13.4%  22.5    1s
  1202   226     cutoff    9         0.48917    0.45068  7.87%  16.1    5s
  4736   269    0.47522   12   10    0.48917    0.46851  4.22%  14.2   10s

Cutting planes:
  Implied bound: 166
  MIR: 48
  Flow cover: 190
  RLT: 13
  Relax-and-lift: 64

Explored 6385 nodes (89392 simplex iterations) in 11.98 seconds (5.60 work units)
Thread count was 4 (of 48 available processors)

Solution count 9: 0.489174 0.492969 0.497363 ... 0.590169

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

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

        Job resource usage summary 

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

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