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 16.6818 seconds.
    local lower bound updated to 0.323353
    local upper bound updated to 0.630027

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.61955
    local lower bound updated to 0.584405

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.612975
    local lower bound updated to 0.610321

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.612975
    local lower bound updated to 0.612775

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0166 seconds.
    local lower bound updated to 0.478192
    local upper bound updated to 0.593139

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.97442 seconds.
    local lower bound updated to 0.541644
    local upper bound updated to 0.591384

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 27.1284 seconds.
    local lower bound updated to 0.582464
    local upper bound updated to 0.590801

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 24.9179 seconds.
    local lower bound updated to 0.585296
    local upper bound updated to 0.587909

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 11.4379 seconds.
    local lower bound updated to 0.585296
    local upper bound updated to 0.587669

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.616575
    local lower bound updated to 0.587669

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.609073
    local lower bound updated to 0.587669

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.608948
    local lower bound updated to 0.593991

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0226 seconds.
    local lower bound updated to 0.501813
    local upper bound updated to 0.59118

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.02 seconds.
    local lower bound updated to 0.583299
    local upper bound updated to 0.589772

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0182 seconds.
    local lower bound updated to 0.586026
    local upper bound updated to 0.588889

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0136 seconds.
    local lower bound updated to 0.586026
    local upper bound updated to 0.588583

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0189 seconds.
    local lower bound updated to 0.587287
    local upper bound updated to 0.588295

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.017 seconds.
    local lower bound updated to 0.587634
    local upper bound updated to 0.588087

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0134 seconds.
    local lower bound updated to 0.587693
    local upper bound updated to 0.588069

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0161 seconds.
    local lower bound updated to 0.588068
    local upper bound updated to 0.588069
The Primal step takes 480.178 (S)
lower bound updated to 0.588068

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.599066
    local lower bound updated to 0.588069

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.589615
    local lower bound updated to 0.588153

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.588372
    local lower bound updated to 0.588177

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0189 seconds.
    local lower bound updated to 0.518234
    local upper bound updated to 0.588177

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.025 seconds.
    local lower bound updated to 0.580759
    local upper bound updated to 0.588154

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0235 seconds.
    local lower bound updated to 0.587035
    local upper bound updated to 0.588144

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0254 seconds.
    local lower bound updated to 0.587582
    local upper bound updated to 0.58814

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0173 seconds.
    local lower bound updated to 0.5879
    local upper bound updated to 0.588121

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0211 seconds.
    local lower bound updated to 0.587937
    local upper bound updated to 0.588121

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0286 seconds.
    local lower bound updated to 0.588071
    local upper bound updated to 0.588121

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0158 seconds.
    local lower bound updated to 0.588106
    local upper bound updated to 0.588121

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.596083
    local lower bound updated to 0.588121

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0168 seconds.
    local lower bound updated to 0.518234
    local upper bound updated to 0.588121

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0281 seconds.
    local lower bound updated to 0.588121
    local upper bound updated to 0.588121
The time obtain the optimal randomized assortment is 1334.52 (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_17_4_60.000000_62818.log"
The computation time limit for a lower bound is 84657
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 2022 rows, 1546 columns and 6714 nonzeros
Model fingerprint: 0xee6c14f7
Variable types: 1481 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [1e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [7e-01, 1e+00]
Presolve removed 424 rows and 810 columns
Presolve time: 0.02s
Presolved: 1598 rows, 736 columns, 6186 nonzeros
Variable types: 674 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.7075443
Found heuristic solution: objective 0.6892888

Root relaxation: objective 4.413529e-01, 200 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.44135    0   19    0.68929    0.44135  36.0%     -    0s
H    0     0                       0.6314377    0.44135  30.1%     -    0s
     0     0    0.47648    0   22    0.63144    0.47648  24.5%     -    0s
     0     0    0.49568    0   30    0.63144    0.49568  21.5%     -    0s
H    0     0                       0.5980499    0.49568  17.1%     -    0s
     0     0    0.49568    0   30    0.59805    0.49568  17.1%     -    0s
     0     0    0.50403    0   28    0.59805    0.50403  15.7%     -    0s
     0     0    0.50451    0   28    0.59805    0.50451  15.6%     -    0s
H    0     0                       0.5941179    0.50451  15.1%     -    0s
H    0     0                       0.5940628    0.50451  15.1%     -    0s
     0     0    0.51797    0   29    0.59406    0.51797  12.8%     -    0s
H    0     0                       0.5889549    0.51797  12.1%     -    0s
     0     0    0.51828    0   31    0.58895    0.51828  12.0%     -    0s
     0     0    0.52048    0   30    0.58895    0.52048  11.6%     -    0s
H    0     0                       0.5881212    0.52048  11.5%     -    2s
     0     2    0.52048    0   30    0.58812    0.52048  11.5%     -    2s
   486   264    0.55371    8   21    0.58812    0.53875  8.40%  28.9    5s
  2073   676    0.58342   14   32    0.58812    0.54996  6.49%  24.7   10s
  2663   791    0.57355   21   10    0.58812    0.55104  6.31%  27.1   15s
  4593   688    0.57011   22    8    0.58812    0.55931  4.90%  26.6   20s
  5893   907     cutoff   26         0.58812    0.56200  4.44%  25.9   25s
  6744  1027     cutoff   31         0.58812    0.56313  4.25%  25.5   30s
  8368  1190     cutoff   30         0.58812    0.56549  3.85%  24.9   35s
  9894  1270 infeasible   27         0.58812    0.56718  3.56%  24.7   40s
 10869  1337 infeasible   27         0.58812    0.56794  3.43%  24.5   45s
 13029  1386     cutoff   30         0.58812    0.56986  3.11%  24.1   50s
 14992  1378     cutoff   29         0.58812    0.57127  2.87%  23.7   55s
 16124  1346    0.58036   27   17    0.58812    0.57190  2.76%  23.4   61s
 17219  1321     cutoff   29         0.58812    0.57283  2.60%  23.3   66s
 18854  1154     cutoff   33         0.58812    0.57422  2.36%  23.1   70s
 21081   908    0.58622   28   13    0.58812    0.57574  2.11%  22.9   76s
 22872   564     cutoff   29         0.58812    0.57788  1.74%  22.7   80s

Cutting planes:
  Implied bound: 172
  MIR: 142
  Flow cover: 306
  RLT: 68
  Relax-and-lift: 58

Explored 24948 nodes (561128 simplex iterations) in 84.93 seconds (62.33 work units)
Thread count was 4 (of 48 available processors)

Solution count 8: 0.588121 0.588955 0.594063 ... 0.707544

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

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

        Job resource usage summary 

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

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