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 3.02547 seconds.
    local lower bound updated to 0.321588
    local upper bound updated to 0.560598

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.718814
    local lower bound updated to 0.686882

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 20.3002 seconds.
    local lower bound updated to 0.402437
    local upper bound updated to 0.565129

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.33337 seconds.
    local lower bound updated to 0.542512
    local upper bound updated to 0.545115

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.91606 seconds.
    local lower bound updated to 0.542512
    local upper bound updated to 0.543576

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 3.34812 seconds.
    local lower bound updated to 0.542512
    local upper bound updated to 0.543155

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 3.94218 seconds.
    local lower bound updated to 0.542512
    local upper bound updated to 0.542817

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.598412
    local lower bound updated to 0.560105

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.569025
    local lower bound updated to 0.561265

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.567905
    local lower bound updated to 0.567695

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 27.2853 seconds.
    local lower bound updated to 0.473751
    local upper bound updated to 0.567695

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.813076 seconds.
    local lower bound updated to 0.496656
    local upper bound updated to 0.56515

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0263 seconds.
    local lower bound updated to 0.552563
    local upper bound updated to 0.564254

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0147 seconds.
    local lower bound updated to 0.559822
    local upper bound updated to 0.564139

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0167 seconds.
    local lower bound updated to 0.560429
    local upper bound updated to 0.563952

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0128 seconds.
    local lower bound updated to 0.560873
    local upper bound updated to 0.563211

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0165 seconds.
    local lower bound updated to 0.56226
    local upper bound updated to 0.562705

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.56226
    local upper bound updated to 0.562639

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.56226
    local upper bound updated to 0.562396

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0166 seconds.
    local lower bound updated to 0.562396
    local upper bound updated to 0.562396
The Primal step takes 508.272 (S)
lower bound updated to 0.562396

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.587886
    local lower bound updated to 0.562396

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.56308
    local lower bound updated to 0.562451

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0251 seconds.
    local lower bound updated to 0.560586
    local upper bound updated to 0.562443

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.58431
    local lower bound updated to 0.562443

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0368 seconds.
    local lower bound updated to 0.490759
    local upper bound updated to 0.562443

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.562443
    local upper bound updated to 0.562443
The time obtain the optimal randomized assortment is 860.177 (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_19_4_60.000000_61320.log"
The computation time limit for a lower bound is 85134
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, 1236 columns and 5660 nonzeros
Model fingerprint: 0x731fa08d
Variable types: 1163 continuous, 73 integer (73 binary)
Coefficient statistics:
  Matrix range     [2e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 315 rows and 577 columns
Presolve time: 0.02s
Presolved: 1365 rows, 659 columns, 5335 nonzeros
Variable types: 590 continuous, 69 integer (69 binary)
Found heuristic solution: objective 0.7005072
Found heuristic solution: objective 0.6697255
Found heuristic solution: objective 0.6502749

Root relaxation: objective 4.454862e-01, 178 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.44549    0   20    0.65027    0.44549  31.5%     -    0s
H    0     0                       0.5873227    0.44549  24.1%     -    0s
     0     0    0.44549    0   20    0.58732    0.44549  24.1%     -    0s
H    0     0                       0.5801773    0.44549  23.2%     -    0s
H    0     0                       0.5728846    0.44549  22.2%     -    0s
     0     0    0.47399    0   30    0.57288    0.47399  17.3%     -    0s
H    0     0                       0.5728755    0.47399  17.3%     -    0s
     0     0    0.47804    0   34    0.57288    0.47804  16.6%     -    0s
     0     0    0.47858    0   33    0.57288    0.47858  16.5%     -    0s
     0     0    0.48517    0   33    0.57288    0.48517  15.3%     -    0s
H    0     0                       0.5637056    0.48517  13.9%     -    0s
     0     0    0.48767    0   34    0.56371    0.48767  13.5%     -    0s
     0     0    0.48963    0   32    0.56371    0.48963  13.1%     -    0s
H    0     0                       0.5624434    0.48963  12.9%     -    1s
     0     2    0.48963    0   32    0.56244    0.48963  12.9%     -    1s
   687   411    0.51149    7   25    0.56244    0.50290  10.6%  19.5    5s
  1098   617    0.52680   11   33    0.56244    0.50817  9.65%  19.7   10s
H 1819   790                       0.5624433    0.51432  8.56%  21.6   12s
  2582   833    0.53658   27   15    0.56244    0.51862  7.79%  21.3   15s
  3282   970    0.55655   30   17    0.56244    0.52064  7.43%  21.3   21s
H 3729  1126                       0.5624433    0.52118  7.34%  21.4   24s
  3767  1129    0.55013   29   20    0.56244    0.52118  7.34%  21.4   25s
  4428  1421     cutoff   31         0.56244    0.52253  7.10%  21.5   30s
  5437  1698    0.53407   26   17    0.56244    0.52424  6.79%  21.3   35s
  7253  2101    0.53917   26   23    0.56244    0.52610  6.46%  21.1   41s
  7816  2302     cutoff   33         0.56244    0.52679  6.34%  21.0   45s
  9671  2635     cutoff   30         0.56244    0.52845  6.04%  20.6   50s
 10699  3018    0.56036   31   14    0.56244    0.52912  5.93%  20.5   55s
 13174  3448    0.53670   29   16    0.56244    0.53058  5.67%  20.3   61s
H13861  3537                       0.5624433    0.53081  5.62%  20.3   63s
 14522  3702    0.53557   26   19    0.56244    0.53134  5.53%  20.2   66s
 15842  3979    0.55429   30   15    0.56244    0.53208  5.40%  20.1   70s
 17876  4272    0.54486   31   14    0.56244    0.53342  5.16%  20.0   77s
 19780  4521    0.55292   30   14    0.56244    0.53430  5.00%  19.9   81s
H20214  4581                       0.5624433    0.53441  4.98%  19.9   82s
 21331  4666     cutoff   25         0.56244    0.53483  4.91%  19.8   85s
 23225  4855 infeasible   38         0.56244    0.53571  4.75%  19.7   90s
 24058  4961    0.55270   32   19    0.56244    0.53611  4.68%  19.7   95s
 25073  5133    0.55160   34   14    0.56244    0.53649  4.61%  19.7  100s
 27008  5266    0.53913   29   16    0.56244    0.53717  4.49%  19.6  105s
 29174  5355     cutoff   34         0.56244    0.53781  4.38%  19.5  110s
 31261  5453    0.55476   31   12    0.56244    0.53840  4.27%  19.4  115s
 33418  5553     cutoff   30         0.56244    0.53903  4.16%  19.3  120s
 35371  5584 infeasible   31         0.56244    0.53956  4.07%  19.2  126s
 36446  5629    0.54929   31   14    0.56244    0.53984  4.02%  19.2  131s
H37792  5646                       0.5624433    0.54014  3.97%  19.2  133s
 37939  5662    0.54657   32   14    0.56244    0.54033  3.93%  19.2  135s
 40243  5746     cutoff   28         0.56244    0.54090  3.83%  19.1  140s
H41650  5765                       0.5624433    0.54121  3.78%  19.1  143s
 42653  5806 infeasible   29         0.56244    0.54154  3.72%  19.1  145s
H43819  5811                       0.5624433    0.54173  3.68%  19.1  148s
 44025  5842    0.55549   30   16    0.56244    0.54182  3.67%  19.1  150s
 45679  5825    0.54757   33   18    0.56244    0.54218  3.60%  19.0  155s
H46100  5826                       0.5624433    0.54231  3.58%  19.0  156s
 47036  5799    0.54839   32   16    0.56244    0.54246  3.55%  19.0  160s
 48807  5788    0.54648   27   17    0.56244    0.54291  3.47%  19.0  165s
 50953  5709    0.55340   32   17    0.56244    0.54356  3.36%  19.0  170s
 53904  5601     cutoff   28         0.56244    0.54416  3.25%  18.9  176s
 55395  5536 infeasible   32         0.56244    0.54459  3.17%  18.9  180s
 57300  5452    0.54653   31   17    0.56244    0.54504  3.09%  18.8  185s
 59323  5333     cutoff   30         0.56244    0.54550  3.01%  18.8  190s
H59800  5333                       0.5624433    0.54563  2.99%  18.8  190s
 60746  5255    0.55384   35    8    0.56244    0.54585  2.95%  18.7  197s
 62145  5125    0.55480   34   12    0.56244    0.54624  2.88%  18.7  200s
 64048  4970    0.55380   27   17    0.56244    0.54662  2.81%  18.7  205s
 65747  4841     cutoff   34         0.56244    0.54708  2.73%  18.6  210s
 67229  4674     cutoff   36         0.56244    0.54749  2.66%  18.6  215s
 69817  4424     cutoff   36         0.56244    0.54822  2.53%  18.6  220s
H71536  4254                       0.5624433    0.54871  2.44%  18.5  224s
 71543  4130     cutoff   36         0.56244    0.54872  2.44%  18.5  226s
 72986  3965    0.55810   34   12    0.56244    0.54913  2.37%  18.5  230s
H73600  3887                       0.5624433    0.54945  2.31%  18.5  231s
 75166  3668    0.56017   33   14    0.56244    0.55010  2.19%  18.4  236s
 77012  3262    0.55674   27   19    0.56244    0.55082  2.07%  18.4  240s
H78822  3100                       0.5624433    0.55140  1.96%  18.3  242s
H79000  3011                       0.5624433    0.55162  1.92%  18.3  244s
H79140  3011                       0.5624433    0.55163  1.92%  18.3  244s
 79241  2901 infeasible   29         0.56244    0.55175  1.90%  18.3  245s
 81707  2189     cutoff   36         0.56244    0.55314  1.65%  18.3  251s
H83300  1780                       0.5624433    0.55410  1.48%  18.2  253s
 84514  1255     cutoff   28         0.56244    0.55577  1.19%  18.2  255s

Cutting planes:
  Implied bound: 198
  MIR: 91
  Flow cover: 230
  RLT: 28
  Relax-and-lift: 102

Explored 86773 nodes (1566703 simplex iterations) in 256.51 seconds (203.04 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.562443 0.562443 0.562443 ... 0.669725

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

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

        Job resource usage summary 

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

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