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 8.943 seconds.
    local lower bound updated to 0.349912
    local upper bound updated to 0.662682

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.674208
    local lower bound updated to 0.64979

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.674208
    local lower bound updated to 0.670092

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 10.0737 seconds.
    local lower bound updated to 0.464318
    local upper bound updated to 0.66019

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.415207 seconds.
    local lower bound updated to 0.55656
    local upper bound updated to 0.636006

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 29.8664 seconds.
    local lower bound updated to 0.623462
    local upper bound updated to 0.630325

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 31.3764 seconds.
    local lower bound updated to 0.628232
    local upper bound updated to 0.629622

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 32.6246 seconds.
    local lower bound updated to 0.628232
    local upper bound updated to 0.629023

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 35.2508 seconds.
    local lower bound updated to 0.628718
    local upper bound updated to 0.628925

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 33.8035 seconds.
    local lower bound updated to 0.628911
    local upper bound updated to 0.62892
The Primal step takes 173.424 (S)
lower bound updated to 0.628911

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.649529
    local lower bound updated to 0.629752

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.648734
    local lower bound updated to 0.632053

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.645864
    local lower bound updated to 0.635176

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.637027
    local lower bound updated to 0.635236

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0115 seconds.
    local lower bound updated to 0.533493
    local upper bound updated to 0.634799

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0116 seconds.
    local lower bound updated to 0.597887
    local upper bound updated to 0.634464

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0143 seconds.
    local lower bound updated to 0.627871
    local upper bound updated to 0.631516

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0138 seconds.
    local lower bound updated to 0.627871
    local upper bound updated to 0.631191

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0137 seconds.
    local lower bound updated to 0.627871
    local upper bound updated to 0.630314

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0139 seconds.
    local lower bound updated to 0.627871
    local upper bound updated to 0.630149

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.627871
    local upper bound updated to 0.630125

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0115 seconds.
    local lower bound updated to 0.628542
    local upper bound updated to 0.629999

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0109 seconds.
    local lower bound updated to 0.628694
    local upper bound updated to 0.629876

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0113 seconds.
    local lower bound updated to 0.629231
    local upper bound updated to 0.629863

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0101 seconds.
    local lower bound updated to 0.62938
    local upper bound updated to 0.629768

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0152 seconds.
    local lower bound updated to 0.62938
    local upper bound updated to 0.629762

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 60.0111 seconds.
    local lower bound updated to 0.629412
    local upper bound updated to 0.629722

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 60.0119 seconds.
    local lower bound updated to 0.62955
    local upper bound updated to 0.629721

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 60.0121 seconds.
    local lower bound updated to 0.629643
    local upper bound updated to 0.62972

    ++ RESTRICTED PRIMAL ITERATION 16 ++
One worst-case parameter optimization takes 60.013 seconds.
    local lower bound updated to 0.62972
    local upper bound updated to 0.62972
The Primal step takes 960.253 (S)
lower bound updated to 0.62972

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.647645
    local lower bound updated to 0.62972

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.640291
    local lower bound updated to 0.630564

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.640137
    local lower bound updated to 0.631118

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.640137
    local lower bound updated to 0.632755

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0138 seconds.
    local lower bound updated to 0.552111
    local upper bound updated to 0.632755

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0117 seconds.
    local lower bound updated to 0.622409
    local upper bound updated to 0.63257

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.629792
    local upper bound updated to 0.632144

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

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

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0161 seconds.
    local lower bound updated to 0.630941
    local upper bound updated to 0.63204

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0122 seconds.
    local lower bound updated to 0.631156
    local upper bound updated to 0.631875

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0174 seconds.
    local lower bound updated to 0.631263
    local upper bound updated to 0.631868

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0113 seconds.
    local lower bound updated to 0.631601
    local upper bound updated to 0.631852

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0122 seconds.
    local lower bound updated to 0.631601
    local upper bound updated to 0.631844

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0127 seconds.
    local lower bound updated to 0.631601
    local upper bound updated to 0.631813

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.013 seconds.
    local lower bound updated to 0.631639
    local upper bound updated to 0.631803

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 60.0118 seconds.
    local lower bound updated to 0.631754
    local upper bound updated to 0.6318

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 60.0134 seconds.
    local lower bound updated to 0.631754
    local upper bound updated to 0.631788

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 60.0132 seconds.
    local lower bound updated to 0.631788
    local upper bound updated to 0.631788
The Primal step takes 900.274 (S)
lower bound updated to 0.631788

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.653116
    local lower bound updated to 0.631788

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.633887
    local lower bound updated to 0.631965

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0159 seconds.
    local lower bound updated to 0.559587
    local upper bound updated to 0.631965

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.014 seconds.
    local lower bound updated to 0.631069
    local upper bound updated to 0.631879

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.651668
    local lower bound updated to 0.631879

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0163 seconds.
    local lower bound updated to 0.559587
    local upper bound updated to 0.631879

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0129 seconds.
    local lower bound updated to 0.631879
    local upper bound updated to 0.631879
The time obtain the optimal randomized assortment is 2373.51 (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_61818.log"
The computation time limit for a lower bound is 83618
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 2410 rows, 1842 columns and 7952 nonzeros
Model fingerprint: 0xe6d6cf19
Variable types: 1777 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [3e-05, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 562 rows and 1008 columns
Presolve time: 0.02s
Presolved: 1848 rows, 834 columns, 7219 nonzeros
Variable types: 772 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.7729573
Found heuristic solution: objective 0.7161014

Root relaxation: objective 4.550686e-01, 227 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.45507    0   20    0.71610    0.45507  36.5%     -    0s
H    0     0                       0.7012242    0.45507  35.1%     -    0s
H    0     0                       0.6889724    0.45702  33.7%     -    0s
     0     0    0.45702    0   24    0.68897    0.45702  33.7%     -    0s
H    0     0                       0.6697189    0.45702  31.8%     -    0s
H    0     0                       0.6695004    0.45702  31.7%     -    0s
     0     0    0.50887    0   29    0.66950    0.50887  24.0%     -    0s
H    0     0                       0.6593951    0.50887  22.8%     -    0s
     0     0    0.50888    0   29    0.65940    0.50888  22.8%     -    0s
H    0     0                       0.6425115    0.50888  20.8%     -    0s
     0     0    0.52100    0   29    0.64251    0.52100  18.9%     -    0s
     0     0    0.52122    0   29    0.64251    0.52122  18.9%     -    0s
     0     0    0.52606    0   29    0.64251    0.52606  18.1%     -    0s
H    0     0                       0.6328312    0.52747  16.6%     -    0s
     0     0    0.52747    0   32    0.63283    0.52747  16.6%     -    0s
     0     0    0.52826    0   31    0.63283    0.52826  16.5%     -    0s
H    0     0                       0.6318791    0.52826  16.4%     -    2s
     0     2    0.52826    0   31    0.63188    0.52826  16.4%     -    2s
   530   351    0.57712   17   17    0.63188    0.54306  14.1%  34.8    5s
H  853   473                       0.6318791    0.54649  13.5%  32.2    7s
  1593   787    0.57162   10   18    0.63188    0.55199  12.6%  30.4   10s
  2097   955    0.57932   16   31    0.63188    0.55420  12.3%  29.4   15s
  2228  1044 infeasible   27         0.63188    0.55420  12.3%  31.4   20s
H 2660   994                       0.6318791    0.55420  12.3%  31.6   22s
  2887  1019    0.57258   22   20    0.63188    0.55523  12.1%  32.2   25s
  4369   982    0.59759   19   22    0.63188    0.56538  10.5%  32.8   30s
  5391  1108    0.60709   28   21    0.63188    0.56818  10.1%  33.0   36s
  6236  1367    0.60703   25   13    0.63188    0.57075  9.67%  33.1   41s
  7365  1621    0.60112   23   17    0.63188    0.57327  9.28%  33.1   45s
  8443  1933    0.62978   29   10    0.63188    0.57473  9.04%  32.7   50s
  9140  2096     cutoff   32         0.63188    0.57554  8.92%  32.6   56s
 10247  2348    0.59586   21   17    0.63188    0.57819  8.50%  32.2   61s
 11379  2442    0.59875   25   16    0.63188    0.57978  8.24%  31.9   65s
 12645  2651     cutoff   30         0.63188    0.58090  8.07%  31.7   70s
H12707  2651                       0.6318791    0.58093  8.06%  31.7   70s
 13935  2934    0.61434   24   16    0.63188    0.58258  7.80%  31.4   77s
 14530  3127    0.58993   21   22    0.63188    0.58334  7.68%  31.1   81s
 16272  3321     cutoff   31         0.63188    0.58487  7.44%  30.8   85s
 18818  3623    0.62179   25   14    0.63188    0.58697  7.11%  30.4   91s
 20120  3750    0.59605   25   16    0.63188    0.58787  6.97%  30.3   95s
 21914  3949     cutoff   29         0.63188    0.58965  6.68%  30.1  100s
 24171  4105     cutoff   23         0.63188    0.59121  6.44%  29.9  105s
 25529  4194    0.60750   20   20    0.63188    0.59218  6.28%  29.7  110s
 26566  4226    0.60526   28   16    0.63188    0.59276  6.19%  29.6  115s
 28489  4340     cutoff   23         0.63188    0.59432  5.94%  29.4  121s
 29478  4390    0.60628   27   14    0.63188    0.59487  5.86%  29.2  125s
 31287  4450    0.62252   27   11    0.63188    0.59614  5.66%  29.1  130s
 32913  4503     cutoff   29         0.63188    0.59709  5.51%  28.9  135s
H33736  4497                       0.6318791    0.59736  5.46%  28.9  138s
 33996  4504    0.61161   26   16    0.63188    0.59766  5.41%  28.9  140s
 36245  4541    0.61411   22   13    0.63188    0.59883  5.23%  28.7  146s
H36512  4541                       0.6318791    0.59885  5.23%  28.7  146s
 37291  4548     cutoff   29         0.63188    0.59929  5.16%  28.7  150s
 38475  4576 infeasible   33         0.63188    0.59995  5.05%  28.6  155s
 40141  4585     cutoff   29         0.63188    0.60090  4.90%  28.5  161s
 41185  4569    0.61312   28   18    0.63188    0.60165  4.78%  28.4  165s
 42757  4527     cutoff   24         0.63188    0.60236  4.67%  28.4  171s
 43726  4526 infeasible   32         0.63188    0.60298  4.57%  28.3  175s
 45322  4435    0.61949   25   14    0.63188    0.60375  4.45%  28.2  181s
 46279  4387     cutoff   26         0.63188    0.60433  4.36%  28.2  185s
 48292  4291    0.61208   26   14    0.63188    0.60541  4.19%  28.0  191s
 49829  4239     cutoff   31         0.63188    0.60619  4.07%  27.9  195s
 51637  4132    0.63097   30    6    0.63188    0.60692  3.95%  27.8  201s
 52917  4015    0.62779   28   12    0.63188    0.60754  3.85%  27.7  205s
 54610  3880    0.61787   32   10    0.63188    0.60855  3.69%  27.6  210s
 56759  3695     cutoff   30         0.63188    0.60960  3.53%  27.4  216s
 58971  3519    0.61771   32   17    0.63188    0.61111  3.29%  27.3  220s
H61001  3271                       0.6318791    0.61206  3.14%  27.1  224s
 61520  3188    0.61839   27   14    0.63188    0.61223  3.11%  27.1  226s
 62372  3053     cutoff   26         0.63188    0.61318  2.96%  27.0  230s
 64300  2759 infeasible   26         0.63188    0.61414  2.81%  26.9  235s
 65642  2547     cutoff   27         0.63188    0.61523  2.63%  26.8  240s
 67129  2193     cutoff   32         0.63188    0.61666  2.41%  26.7  246s
 69839  1464     cutoff   32         0.63188    0.61912  2.02%  26.4  250s
 72781   166    0.62987   33    2    0.63188    0.62344  1.34%  26.1  255s

Cutting planes:
  Implied bound: 237
  MIR: 143
  Flow cover: 405
  RLT: 86
  Relax-and-lift: 96
  BQP: 1

Explored 73684 nodes (1909220 simplex iterations) in 255.41 seconds (281.91 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.631879 0.631879 0.632831 ... 0.716101

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

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

        Job resource usage summary 

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

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