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 5.33852 seconds.
    local lower bound updated to 0.29421
    local upper bound updated to 0.54937

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.630168
    local lower bound updated to 0.629245

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.630168
    local lower bound updated to 0.6294

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 6.4991 seconds.
    local lower bound updated to 0.393535
    local upper bound updated to 0.512915

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.39902 seconds.
    local lower bound updated to 0.509533
    local upper bound updated to 0.510811

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.545526
    local lower bound updated to 0.510811

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.545526
    local lower bound updated to 0.510811

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.527288
    local lower bound updated to 0.518294

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.527288
    local lower bound updated to 0.526133

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.527288
    local lower bound updated to 0.526237

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.527288
    local lower bound updated to 0.526374

    ++ RESTRICTED DUAL ITERATION 7 ++
    local upper bound updated to 0.527288
    local lower bound updated to 0.52643

    ++ RESTRICTED DUAL ITERATION 8 ++
    local upper bound updated to 0.52643
    local lower bound updated to 0.52643
The Dual step takes 2.3491 (S)
upper bound updated to 0.52643
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0191 seconds.
    local lower bound updated to 0.461507
    local upper bound updated to 0.525742

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 15.7413 seconds.
    local lower bound updated to 0.490458
    local upper bound updated to 0.520695

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 6.90441 seconds.
    local lower bound updated to 0.490458
    local upper bound updated to 0.520358

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 16.4304 seconds.
    local lower bound updated to 0.504453
    local upper bound updated to 0.519707

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 7.54697 seconds.
    local lower bound updated to 0.504453
    local upper bound updated to 0.516513

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 28.2772 seconds.
    local lower bound updated to 0.504453
    local upper bound updated to 0.516214

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 16.7439 seconds.
    local lower bound updated to 0.50864
    local upper bound updated to 0.5158

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 10.5713 seconds.
    local lower bound updated to 0.512117
    local upper bound updated to 0.51579

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 23.0473 seconds.
    local lower bound updated to 0.515309
    local upper bound updated to 0.515783

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 17.8809 seconds.
    local lower bound updated to 0.515309
    local upper bound updated to 0.51557

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 26.9449 seconds.
    local lower bound updated to 0.515309
    local upper bound updated to 0.515545

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 24.328 seconds.
    local lower bound updated to 0.515545
    local upper bound updated to 0.515545
The Primal step takes 254.496 (S)
lower bound updated to 0.515545

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.539295
    local lower bound updated to 0.515545

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.516471
    local lower bound updated to 0.515758

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0328 seconds.
    local lower bound updated to 0.465986
    local upper bound updated to 0.515758

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0296 seconds.
    local lower bound updated to 0.515627
    local upper bound updated to 0.515752

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.023 seconds.
    local lower bound updated to 0.515734
    local upper bound updated to 0.51575

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0223 seconds.
    local lower bound updated to 0.51575
    local upper bound updated to 0.51575
The Primal step takes 240.13 (S)
lower bound updated to 0.51575

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.533681
    local lower bound updated to 0.51575

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0218 seconds.
    local lower bound updated to 0.467476
    local upper bound updated to 0.51575

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0231 seconds.
    local lower bound updated to 0.51575
    local upper bound updated to 0.51575
The time obtain the optimal randomized assortment is 644.361 (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_64418.log"
The computation time limit for a lower bound is 85346
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 128 physical cores, 256 logical processors, using up to 4 threads
Optimize a model with 2604 rows, 1990 columns and 8533 nonzeros
Model fingerprint: 0x4474e5ed
Variable types: 1925 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 598 rows and 1098 columns
Presolve time: 0.03s
Presolved: 2006 rows, 892 columns, 7871 nonzeros
Variable types: 829 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6327913
Found heuristic solution: objective 0.6188567

Root relaxation: objective 3.407773e-01, 258 iterations, 0.01 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.34078    0   24    0.61886    0.34078  44.9%     -    0s
H    0     0                       0.5521530    0.34078  38.3%     -    0s
H    0     0                       0.5510400    0.34078  38.2%     -    0s
     0     0    0.34316    0   28    0.55104    0.34316  37.7%     -    0s
H    0     0                       0.5504958    0.34316  37.7%     -    0s
H    0     0                       0.5271940    0.37594  28.7%     -    0s
     0     0    0.37594    0   26    0.52719    0.37594  28.7%     -    0s
     0     0    0.37595    0   25    0.52719    0.37595  28.7%     -    0s
     0     0    0.38908    0   29    0.52719    0.38908  26.2%     -    0s
     0     0    0.39706    0   29    0.52719    0.39706  24.7%     -    0s
     0     0    0.41265    0   29    0.52719    0.41265  21.7%     -    0s
H    0     0                       0.5180497    0.41265  20.3%     -    0s
     0     0    0.41369    0   31    0.51805    0.41369  20.1%     -    0s
     0     0    0.41693    0   29    0.51805    0.41693  19.5%     -    0s
H    0     0                       0.5163220    0.41693  19.3%     -    0s
H    0     0                       0.5157504    0.41693  19.2%     -    2s
     0     2    0.41693    0   29    0.51575    0.41693  19.2%     -    2s
   390   184    0.46028    6   20    0.51575    0.44848  13.0%  29.8    5s
H  531   234                       0.5157504    0.44917  12.9%  29.7    7s
   786   307    0.51181   13   12    0.51575    0.45757  11.3%  27.9   10s
  2275   706    0.50004   17    6    0.51575    0.47044  8.78%  24.8   15s
  3774   944    0.51357   12   10    0.51575    0.47692  7.53%  23.6   21s
  4210   989    0.48130   12   30    0.51575    0.47802  7.32%  23.4   25s
H 4617  1007                       0.5157504    0.47802  7.32%  26.0   28s
  4998   989     cutoff   26         0.51575    0.47802  7.32%  26.5   30s
H 5920   755                       0.5157504    0.48093  6.75%  26.9   34s
H 5961   697                       0.5157504    0.48093  6.75%  26.8   34s
  5962   701     cutoff   23         0.51575    0.48133  6.67%  26.9   35s
  7064   526    0.49883   23    8    0.51575    0.48614  5.74%  27.1   40s
  8045   615    0.51004   23   16    0.51575    0.48909  5.17%  27.0   46s
  9164   661    0.50283   19   16    0.51575    0.49166  4.67%  27.1   50s
 10278   710     cutoff   22         0.51575    0.49340  4.33%  26.8   55s
 12419   639 infeasible   20         0.51575    0.49686  3.66%  26.2   60s
 14374   513     cutoff   23         0.51575    0.50048  2.96%  25.8   65s
 15877   324     cutoff   21         0.51575    0.50322  2.43%  25.5   70s

Cutting planes:
  Implied bound: 215
  MIR: 140
  Flow cover: 426
  RLT: 80
  Relax-and-lift: 78

Explored 17079 nodes (432159 simplex iterations) in 71.26 seconds (50.99 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.51575 0.51575 0.51575 ... 0.55104

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

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

        Job resource usage summary 

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

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