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.346947 seconds.
    local lower bound updated to 0.221852
    local upper bound updated to 0.382686

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.429943
    local lower bound updated to 0.426253

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.58981 seconds.
    local lower bound updated to 0.276806
    local upper bound updated to 0.392726

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.93116 seconds.
    local lower bound updated to 0.376683
    local upper bound updated to 0.381703

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 0.723947 seconds.
    local lower bound updated to 0.380367
    local upper bound updated to 0.380555

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 0.718018 seconds.
    local lower bound updated to 0.380368
    local upper bound updated to 0.380523

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.414735
    local lower bound updated to 0.404237

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.409252
    local lower bound updated to 0.405753

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.409252
    local lower bound updated to 0.405753

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.407771
    local lower bound updated to 0.407024

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 9.27751 seconds.
    local lower bound updated to 0.353707
    local upper bound updated to 0.404949

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.13054 seconds.
    local lower bound updated to 0.378346
    local upper bound updated to 0.398646

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.59878 seconds.
    local lower bound updated to 0.38093
    local upper bound updated to 0.397629

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.5854 seconds.
    local lower bound updated to 0.38093
    local upper bound updated to 0.397419

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 3.79258 seconds.
    local lower bound updated to 0.392718
    local upper bound updated to 0.395891

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 2.07603 seconds.
    local lower bound updated to 0.394488
    local upper bound updated to 0.395857

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 3.3054 seconds.
    local lower bound updated to 0.394831
    local upper bound updated to 0.395801

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 3.02418 seconds.
    local lower bound updated to 0.395558
    local upper bound updated to 0.395791

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 3.64953 seconds.
    local lower bound updated to 0.395667
    local upper bound updated to 0.395785

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 4.13562 seconds.
    local lower bound updated to 0.395776
    local upper bound updated to 0.39578
The Primal step takes 33.6181 (S)
lower bound updated to 0.395776

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.419877
    local lower bound updated to 0.39578

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.418975
    local lower bound updated to 0.399394

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.407672
    local lower bound updated to 0.399989

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.402624
    local lower bound updated to 0.400968

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 6.8967 seconds.
    local lower bound updated to 0.363314
    local upper bound updated to 0.399854

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.87715 seconds.
    local lower bound updated to 0.394274
    local upper bound updated to 0.398688

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 4.39672 seconds.
    local lower bound updated to 0.395288
    local upper bound updated to 0.398324

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 3.90586 seconds.
    local lower bound updated to 0.396367
    local upper bound updated to 0.398001

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 4.84627 seconds.
    local lower bound updated to 0.397028
    local upper bound updated to 0.397814

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 5.96989 seconds.
    local lower bound updated to 0.397246
    local upper bound updated to 0.397792

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 6.79501 seconds.
    local lower bound updated to 0.397726
    local upper bound updated to 0.397792

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 5.33328 seconds.
    local lower bound updated to 0.397758
    local upper bound updated to 0.397791

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.417117
    local lower bound updated to 0.397791

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.399348
    local lower bound updated to 0.398186

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.398216
    local lower bound updated to 0.398187

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 13.0316 seconds.
    local lower bound updated to 0.370522
    local upper bound updated to 0.398187

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 6.75279 seconds.
    local lower bound updated to 0.398115
    local upper bound updated to 0.398183

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 5.62452 seconds.
    local lower bound updated to 0.398173
    local upper bound updated to 0.398182
The Primal step takes 25.4289 (S)
lower bound updated to 0.398173

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.417854
    local lower bound updated to 0.398182

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 12.7648 seconds.
    local lower bound updated to 0.370522
    local upper bound updated to 0.398182

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 7.97275 seconds.
    local lower bound updated to 0.398182
    local upper bound updated to 0.398182
The time obtain the optimal randomized assortment is 147.563 (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_13_4_200.000000_204514.log"
The computation time limit for a lower bound is 85849
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 1590 rows, 1394 columns and 4941 nonzeros
Model fingerprint: 0x121dd97f
Variable types: 1345 continuous, 49 integer (49 binary)
Coefficient statistics:
  Matrix range     [4e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 503 rows and 921 columns
Presolve time: 0.02s
Presolved: 1087 rows, 473 columns, 4118 nonzeros
Variable types: 426 continuous, 47 integer (47 binary)
Found heuristic solution: objective 0.4453799
Found heuristic solution: objective 0.4331967

Root relaxation: objective 2.137134e-01, 132 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.21371    0   19    0.43320    0.21371  50.7%     -    0s
H    0     0                       0.4047303    0.21371  47.2%     -    0s
H    0     0                       0.4022593    0.23616  41.3%     -    0s
     0     0    0.23873    0   20    0.40226    0.23873  40.7%     -    0s
H    0     0                       0.4018236    0.28606  28.8%     -    0s
     0     0    0.28606    0   23    0.40182    0.28606  28.8%     -    0s
     0     0    0.28726    0   22    0.40182    0.28726  28.5%     -    0s
     0     0    0.30332    0   23    0.40182    0.30332  24.5%     -    0s
     0     0    0.30591    0   22    0.40182    0.30591  23.9%     -    0s
     0     0    0.31608    0   21    0.40182    0.31608  21.3%     -    0s
     0     0    0.31608    0   22    0.40182    0.31608  21.3%     -    0s
     0     0    0.31756    0   20    0.40182    0.31756  21.0%     -    0s
H    0     0                       0.3984168    0.31756  20.3%     -    1s
     0     2    0.31756    0   20    0.39842    0.31756  20.3%     -    1s
H   57    32                       0.3983324    0.33521  15.8%  28.5    1s
*  286   123              14       0.3981820    0.35169  11.7%  21.5    1s
H  569   150                       0.3981820    0.36201  9.09%  18.9    1s
  1589   193    0.39454   14    4    0.39818    0.37721  5.27%  16.9    5s
H 3313    19                       0.3981820    0.39379  1.10%  14.7    8s

Cutting planes:
  Gomory: 2
  Implied bound: 153
  MIR: 46
  Flow cover: 196
  RLT: 7
  Relax-and-lift: 73

Explored 3352 nodes (50244 simplex iterations) in 8.41 seconds (3.71 work units)
Thread count was 4 (of 40 available processors)

Solution count 9: 0.398182 0.398182 0.398332 ... 0.44538

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

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

        Job resource usage summary 

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

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