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 4.62494 seconds.
    local lower bound updated to 0.286865
    local upper bound updated to 0.557847

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.665356
    local lower bound updated to 0.660801

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 18.7363 seconds.
    local lower bound updated to 0.384756
    local upper bound updated to 0.554943

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.690847 seconds.
    local lower bound updated to 0.505934
    local upper bound updated to 0.554827

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 6.9648 seconds.
    local lower bound updated to 0.543795
    local upper bound updated to 0.554742

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 9.43454 seconds.
    local lower bound updated to 0.548579
    local upper bound updated to 0.550156

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.601707
    local lower bound updated to 0.579988

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 31.219 seconds.
    local lower bound updated to 0.420698
    local upper bound updated to 0.559711

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 19.821 seconds.
    local lower bound updated to 0.553886
    local upper bound updated to 0.555079

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 17.5538 seconds.
    local lower bound updated to 0.554015
    local upper bound updated to 0.554844

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 16.6943 seconds.
    local lower bound updated to 0.554015
    local upper bound updated to 0.554543

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.586743
    local lower bound updated to 0.565691

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.581143
    local lower bound updated to 0.570757

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.573639
    local lower bound updated to 0.571224

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.573061
    local lower bound updated to 0.57145

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.494762
    local upper bound updated to 0.57145

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 19.68 seconds.
    local lower bound updated to 0.518965
    local upper bound updated to 0.570805

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 49.6565 seconds.
    local lower bound updated to 0.552111
    local upper bound updated to 0.569291

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.558279
    local upper bound updated to 0.56919

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.566647
    local upper bound updated to 0.569162

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.018 seconds.
    local lower bound updated to 0.567976
    local upper bound updated to 0.569157

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.567976
    local upper bound updated to 0.569095

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.028 seconds.
    local lower bound updated to 0.568331
    local upper bound updated to 0.56868

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.568331
    local upper bound updated to 0.568639

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.568453
    local upper bound updated to 0.568636

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.568513
    local upper bound updated to 0.568602

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.568539
    local upper bound updated to 0.568601

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.568601
    local upper bound updated to 0.568601
The Primal step takes 1169.68 (S)
lower bound updated to 0.568601

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.598928
    local lower bound updated to 0.568601

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.568965
    local lower bound updated to 0.568665

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 79.271 seconds.
    local lower bound updated to 0.492521
    local upper bound updated to 0.568665

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.560224
    local upper bound updated to 0.568626

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.567138
    local upper bound updated to 0.56861

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.568543
    local upper bound updated to 0.568606

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.592537
    local lower bound updated to 0.568606

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 78.273 seconds.
    local lower bound updated to 0.492521
    local upper bound updated to 0.568606

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.568606
    local upper bound updated to 0.568606
The time obtain the optimal randomized assortment is 2002.45 (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_100.000000_102018.log"
The computation time limit for a lower bound is 83989
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 1828 rows, 1398 columns and 6046 nonzeros
Model fingerprint: 0xf635257a
Variable types: 1333 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        [3e-01, 1e+00]
Presolve removed 397 rows and 743 columns
Presolve time: 0.03s
Presolved: 1431 rows, 655 columns, 5586 nonzeros
Variable types: 593 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.7116660
Found heuristic solution: objective 0.6151157

Root relaxation: objective 3.855100e-01, 148 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.38551    0   16    0.61512    0.38551  37.3%     -    0s
H    0     0                       0.6108119    0.38551  36.9%     -    0s
H    0     0                       0.6073629    0.38634  36.4%     -    0s
     0     0    0.38762    0   25    0.60736    0.38762  36.2%     -    0s
H    0     0                       0.5942567    0.38762  34.8%     -    0s
H    0     0                       0.5903575    0.43579  26.2%     -    0s
     0     0    0.43579    0   33    0.59036    0.43579  26.2%     -    0s
     0     0    0.43580    0   34    0.59036    0.43580  26.2%     -    0s
H    0     0                       0.5838675    0.43580  25.4%     -    0s
     0     0    0.45311    0   32    0.58387    0.45311  22.4%     -    0s
     0     0    0.45337    0   31    0.58387    0.45337  22.3%     -    0s
     0     0    0.45851    0   33    0.58387    0.45851  21.5%     -    0s
     0     0    0.45935    0   33    0.58387    0.45935  21.3%     -    0s
H    0     0                       0.5789309    0.45935  20.7%     -    0s
H    0     0                       0.5787131    0.45935  20.6%     -    0s
     0     0    0.46138    0   29    0.57871    0.46138  20.3%     -    0s
H    0     0                       0.5703053    0.46138  19.1%     -    1s
     0     2    0.46138    0   29    0.57031    0.46138  19.1%     -    1s
H  100    81                       0.5686058    0.47396  16.6%  24.2    3s
   297   230    0.50839    7   17    0.56861    0.47992  15.6%  22.9    6s
H  680   402                       0.5686058    0.48673  14.4%  18.9    7s
H  804   445                       0.5686058    0.48992  13.8%  18.5    9s
   878   503    0.55386   11   10    0.56861    0.49101  13.6%  18.4   10s
H 1020   554                       0.5686058    0.49124  13.6%  18.1   10s
H 1023   554                       0.5686058    0.49124  13.6%  18.2   11s
  1099   614    0.55789   18   25    0.56861    0.49124  13.6%  20.7   15s
H 1281   668                       0.5686058    0.49124  13.6%  21.5   15s
H 1303   637                       0.5686058    0.49124  13.6%  21.5   17s
  1960   763    0.54321   20   16    0.56861    0.49906  12.2%  22.3   20s
  3990  1226    0.52430   23   16    0.56861    0.51128  10.1%  22.6   25s
  5376  1601    0.54410   23   10    0.56861    0.51547  9.35%  23.0   31s
  6816  1968    0.54976   26   21    0.56861    0.51950  8.64%  23.2   35s
  7981  2179 infeasible   21         0.56861    0.52179  8.23%  23.3   40s
H 8600  2275                       0.5686058    0.52245  8.12%  23.5   41s
  9623  2437    0.54436   21   19    0.56861    0.52430  7.79%  23.3   45s
 11486  2685     cutoff   24         0.56861    0.52649  7.41%  23.2   51s
 12739  2876    0.54162   31   12    0.56861    0.52828  7.09%  23.1   55s
 15037  3102    0.55491   22   14    0.56861    0.53067  6.67%  23.1   60s
 17329  3250    0.54830   23   12    0.56861    0.53317  6.23%  22.9   65s
H19138  3333                       0.5686058    0.53469  5.96%  22.9   69s
 19215  3357     cutoff   26         0.56861    0.53476  5.95%  22.9   70s
 21234  3426     cutoff   34         0.56861    0.53609  5.72%  22.8   76s
H21869  3426                       0.5686058    0.53648  5.65%  22.8   76s
 23721  3496     cutoff   28         0.56861    0.53792  5.40%  22.7   81s
 25423  3513    0.56550   24   14    0.56861    0.53894  5.22%  22.7   85s
H26056  3487                       0.5686058    0.53935  5.14%  22.6   86s
 27255  3436    0.55635   19   20    0.56861    0.54019  5.00%  22.6   90s
H27786  3436                       0.5686058    0.54092  4.87%  22.5   90s
H28000  3433                       0.5686058    0.54092  4.87%  22.5   91s
H29475  3373                       0.5686058    0.54187  4.70%  22.5   94s
 29476  3343    0.55830   31    6    0.56861    0.54189  4.70%  22.5   96s
 31737  3214    0.55984   28    6    0.56861    0.54343  4.43%  22.3  100s
H33471  3083                       0.5686058    0.54446  4.25%  22.2  103s
 33786  3010    0.55241   28    8    0.56861    0.54516  4.12%  22.2  105s
H35200  2891                       0.5686058    0.54605  3.97%  22.2  108s
 36031  2729 infeasible   32         0.56861    0.54669  3.85%  22.1  111s
H36800  2650                       0.5686058    0.54724  3.76%  22.1  112s
 38216  2391    0.56406   27   12    0.56861    0.54871  3.50%  22.0  115s
H39106  2327                       0.5686058    0.54912  3.43%  22.0  117s
H39517  2208                       0.5686058    0.54917  3.42%  22.0  118s
 40245  1958     cutoff   30         0.56861    0.55057  3.17%  21.9  121s
H40840  1958                       0.5686058    0.55094  3.11%  21.9  121s
H41220  1817                       0.5686058    0.55096  3.10%  21.9  122s
 42241  1313     cutoff   27         0.56861    0.55217  2.89%  21.8  126s
 45463     0     cutoff   32         0.56861    0.56565  0.52%  21.4  130s

Cutting planes:
  Implied bound: 185
  MIR: 110
  Flow cover: 293
  RLT: 55
  Relax-and-lift: 71

Explored 45585 nodes (976825 simplex iterations) in 130.04 seconds (103.03 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.568606 0.568606 0.568606 ... 0.594257

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

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

        Job resource usage summary 

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

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