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 1.92053 seconds.
    local lower bound updated to 0.252222
    local upper bound updated to 0.484684

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.546276
    local lower bound updated to 0.520939

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 55.8297 seconds.
    local lower bound updated to 0.365838
    local upper bound updated to 0.48788

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.77567 seconds.
    local lower bound updated to 0.449168
    local upper bound updated to 0.481869

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 15.2323 seconds.
    local lower bound updated to 0.453613
    local upper bound updated to 0.460461

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 7.15119 seconds.
    local lower bound updated to 0.459437
    local upper bound updated to 0.460179

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 12.9866 seconds.
    local lower bound updated to 0.459437
    local upper bound updated to 0.459553

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.503181
    local lower bound updated to 0.487956

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.503181
    local lower bound updated to 0.491527

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.503181
    local lower bound updated to 0.494125

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.499785
    local lower bound updated to 0.495598

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.498744
    local lower bound updated to 0.496311

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 38.4496 seconds.
    local lower bound updated to 0.407034
    local upper bound updated to 0.491218

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 7.14473 seconds.
    local lower bound updated to 0.447756
    local upper bound updated to 0.485427

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 17.8785 seconds.
    local lower bound updated to 0.464931
    local upper bound updated to 0.481826

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.09 seconds.
    local lower bound updated to 0.473434
    local upper bound updated to 0.479173

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 37.5514 seconds.
    local lower bound updated to 0.473434
    local upper bound updated to 0.47833

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.093 seconds.
    local lower bound updated to 0.473434
    local upper bound updated to 0.478118

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.129 seconds.
    local lower bound updated to 0.473434
    local upper bound updated to 0.478108

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.069 seconds.
    local lower bound updated to 0.474706
    local upper bound updated to 0.476402

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.092 seconds.
    local lower bound updated to 0.475761
    local upper bound updated to 0.476233

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.09 seconds.
    local lower bound updated to 0.475983
    local upper bound updated to 0.476227

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.063 seconds.
    local lower bound updated to 0.476227
    local upper bound updated to 0.476227
The Primal step takes 801.874 (S)
lower bound updated to 0.476227

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.497686
    local lower bound updated to 0.481562

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.496037
    local lower bound updated to 0.481864

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.495578
    local lower bound updated to 0.482213

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.493367
    local lower bound updated to 0.483433

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.48558
    local lower bound updated to 0.483476

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.114 seconds.
    local lower bound updated to 0.427446
    local upper bound updated to 0.483476

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.106 seconds.
    local lower bound updated to 0.470772
    local upper bound updated to 0.481911

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.129 seconds.
    local lower bound updated to 0.476457
    local upper bound updated to 0.481106

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.133 seconds.
    local lower bound updated to 0.476727
    local upper bound updated to 0.480784

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.105 seconds.
    local lower bound updated to 0.4776
    local upper bound updated to 0.480559

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.112 seconds.
    local lower bound updated to 0.478003
    local upper bound updated to 0.480327

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.079 seconds.
    local lower bound updated to 0.478003
    local upper bound updated to 0.480133

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.079 seconds.
    local lower bound updated to 0.478003
    local upper bound updated to 0.479814

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.06 seconds.
    local lower bound updated to 0.478192
    local upper bound updated to 0.479706

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.046 seconds.
    local lower bound updated to 0.478232
    local upper bound updated to 0.479441

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.075 seconds.
    local lower bound updated to 0.47834
    local upper bound updated to 0.479418

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.071 seconds.
    local lower bound updated to 0.478849
    local upper bound updated to 0.479377

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.079 seconds.
    local lower bound updated to 0.478981
    local upper bound updated to 0.479366

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 100.043 seconds.
    local lower bound updated to 0.478981
    local upper bound updated to 0.479338

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 100.054 seconds.
    local lower bound updated to 0.479061
    local upper bound updated to 0.479327

    ++ RESTRICTED PRIMAL ITERATION 16 ++
One worst-case parameter optimization takes 100.061 seconds.
    local lower bound updated to 0.479061
    local upper bound updated to 0.479318

    ++ RESTRICTED PRIMAL ITERATION 17 ++
One worst-case parameter optimization takes 100.108 seconds.
    local lower bound updated to 0.479308
    local upper bound updated to 0.479317
The Primal step takes 1701.82 (S)
lower bound updated to 0.479308

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.495522
    local lower bound updated to 0.480396

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.482299
    local lower bound updated to 0.480456

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.482299
    local lower bound updated to 0.48052

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.48217
    local lower bound updated to 0.480558

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.481743
    local lower bound updated to 0.480569

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.48057
    local lower bound updated to 0.480569
The Dual step takes 119.768 (S)
upper bound updated to 0.48057
*** OUTER ITERATION 4 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.226 seconds.
    local lower bound updated to 0.437863
    local upper bound updated to 0.480569

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.192 seconds.
    local lower bound updated to 0.480081
    local upper bound updated to 0.480541

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.134 seconds.
    local lower bound updated to 0.480428
    local upper bound updated to 0.480534

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.104 seconds.
    local lower bound updated to 0.480428
    local upper bound updated to 0.480511

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.495174
    local lower bound updated to 0.480511

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.097 seconds.
    local lower bound updated to 0.437863
    local upper bound updated to 0.480511

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.129 seconds.
    local lower bound updated to 0.480511
    local upper bound updated to 0.480511
The time obtain the optimal randomized assortment is 3497.96 (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_108018.log"
The computation time limit for a lower bound is 82495
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 3768 rows, 2878 columns and 12390 nonzeros
Model fingerprint: 0x2154d652
Variable types: 2813 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [8e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 879 rows and 1597 columns
Presolve time: 0.11s
Presolved: 2889 rows, 1281 columns, 11167 nonzeros
Variable types: 1218 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6880560
Found heuristic solution: objective 0.6741459
Found heuristic solution: objective 0.5930127

Root relaxation: objective 3.547263e-01, 341 iterations, 0.02 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.35473    0   24    0.59301    0.35473  40.2%     -    0s
H    0     0                       0.5880900    0.35473  39.7%     -    0s
H    0     0                       0.5767175    0.35473  38.5%     -    0s
H    0     0                       0.5561940    0.35915  35.4%     -    0s
     0     0    0.35915    0   30    0.55619    0.35915  35.4%     -    1s
H    0     0                       0.5049695    0.35915  28.9%     -    1s
     0     0    0.35915    0   30    0.50497    0.35915  28.9%     -    1s
     0     0    0.39238    0   29    0.50497    0.39238  22.3%     -    1s
H    0     0                       0.4877996    0.39243  19.6%     -    1s
     0     0    0.39243    0   29    0.48780    0.39243  19.6%     -    1s
H    0     0                       0.4869071    0.40667  16.5%     -    2s
H    0     0                       0.4846631    0.40667  16.1%     -    2s
     0     0    0.40667    0   28    0.48466    0.40667  16.1%     -    2s
H    0     0                       0.4814647    0.40667  15.5%     -    2s
     0     0    0.40736    0   30    0.48146    0.40736  15.4%     -    2s
     0     0    0.41023    0   30    0.48146    0.41023  14.8%     -    2s
     0     0    0.41111    0   29    0.48146    0.41111  14.6%     -    2s
     0     0    0.41286    0   31    0.48146    0.41286  14.2%     -    3s
H    0     0                       0.4805115    0.41286  14.1%     -   10s
     0     2    0.41286    0   31    0.48051    0.41286  14.1%     -   11s
   100    73    0.44282    5   26    0.48051    0.42449  11.7%  48.9   18s
   197   137     cutoff   15         0.48051    0.42449  11.7%  44.8   24s
H  200   137                       0.4805115    0.42449  11.7%  44.5   24s
   228   133    0.42902    4   26    0.48051    0.42823  10.9%  46.8   25s
H  302   191                       0.4805115    0.42903  10.7%  46.5   26s
   421   225    0.44381    6   21    0.48051    0.43305  9.88%  43.7   30s
   593   297    0.47660   17   13    0.48051    0.43739  8.97%  41.9   39s
   627   312    0.44121    6   22    0.48051    0.43746  8.96%  41.3   40s
H  648   312                       0.4805115    0.43746  8.96%  40.7   40s
  1283   496    0.45944   10   17    0.48051    0.44186  8.04%  36.0   45s
  1660   609     cutoff   20         0.48051    0.44369  7.66%  35.1   50s
  1891   649    0.46853   12   12    0.48051    0.44479  7.43%  34.0   57s
  1972   683    0.47022   10   18    0.48051    0.44500  7.39%  33.9   62s
  2200   757    0.45583   14    8    0.48051    0.44598  7.19%  33.0   65s
  2560   825     cutoff   13         0.48051    0.44692  6.99%  32.8   70s
  2972   925     cutoff   17         0.48051    0.44787  6.79%  32.1   76s
  3112   926    0.45838   10   31    0.48051    0.44801  6.76%  31.8   83s
  3122   933    0.47437   13   36    0.48051    0.44801  6.76%  31.7   85s
  3130   938    0.45044   13   33    0.48051    0.44801  6.76%  31.7   93s
  3181   975    0.45261   23   21    0.48051    0.44801  6.76%  34.2   95s
  3335  1035    0.45762   24   17    0.48051    0.44801  6.76%  34.6  100s
  3900  1023     cutoff   28         0.48051    0.44801  6.76%  35.6  106s
  4081  1004    0.45925   24   17    0.48051    0.44801  6.76%  35.9  110s
  4282   970    0.46744   25   17    0.48051    0.44801  6.76%  36.3  116s
H 4329   906                       0.4805115    0.44801  6.76%  36.3  116s
  4667   927    0.47268   32   10    0.48051    0.44883  6.59%  36.6  123s
  4972   873     cutoff   28         0.48051    0.45012  6.32%  36.8  127s
  5080   870    0.47489   24   17    0.48051    0.45067  6.21%  36.8  132s
H 5160   798                       0.4805115    0.45077  6.19%  36.7  132s
  5180   803    0.47014   31    6    0.48051    0.45077  6.19%  36.8  137s
  5546   730    0.47137   23   24    0.48051    0.45142  6.06%  36.8  143s
  5936   753     cutoff   31         0.48051    0.45288  5.75%  36.8  145s
  6639   872    0.46919   34    8    0.48051    0.45441  5.43%  36.7  153s
  6928   927    0.47264   28   14    0.48051    0.45492  5.33%  36.5  158s
  7473   987 infeasible   26         0.48051    0.45591  5.12%  36.3  164s
  7637   991    0.47499   30   16    0.48051    0.45611  5.08%  36.2  170s
  8206  1075     cutoff   33         0.48051    0.45665  4.97%  36.0  176s
  8559  1105    0.47042   26   12    0.48051    0.45693  4.91%  36.0  183s
  8857  1144    0.46750   25   17    0.48051    0.45743  4.80%  35.9  186s
  9561  1203    0.46609   30   10    0.48051    0.45831  4.62%  35.6  192s
 10071  1246    0.46138   28   14    0.48051    0.45876  4.53%  35.4  198s
 10491  1269    0.46750   28   15    0.48051    0.45900  4.48%  35.3  204s
 10827  1283    0.47808   31   17    0.48051    0.45929  4.42%  35.1  209s
 11339  1300     cutoff   32         0.48051    0.46013  4.24%  35.0  214s
 11926  1326     cutoff   29         0.48051    0.46058  4.15%  34.8  217s
 12553  1338     cutoff   26         0.48051    0.46117  4.02%  34.7  223s
 13045  1337    0.47361   27   17    0.48051    0.46143  3.97%  34.6  228s
 13269  1353    0.46659   30   15    0.48051    0.46176  3.90%  34.4  234s
 13733  1368    0.47791   28   14    0.48051    0.46189  3.88%  34.3  238s
 14620  1383    0.47036   28   13    0.48051    0.46240  3.77%  34.1  242s
 15485  1367 infeasible   34         0.48051    0.46359  3.52%  33.9  249s
 15783  1373    0.47417   32    4    0.48051    0.46382  3.47%  33.8  257s
 16317  1357     cutoff   27         0.48051    0.46426  3.38%  33.6  265s
 17300  1339     cutoff   32         0.48051    0.46489  3.25%  33.5  278s
 17704  1318    0.47369   33    8    0.48051    0.46497  3.23%  33.3  283s
 18386  1267    0.47770   33    6    0.48051    0.46577  3.07%  33.2  289s
 19009  1181    0.47559   32    8    0.48051    0.46591  3.04%  33.0  296s
 20313  1081    0.47613   31   11    0.48051    0.46719  2.77%  32.8  303s
 21076  1009    0.47973   29   12    0.48051    0.46786  2.63%  32.6  307s
 21659   956    0.47841   26   17    0.48051    0.46863  2.47%  32.6  313s
 22027   871    0.47409   29   14    0.48051    0.46890  2.42%  32.5  318s
 22666   753    0.47906   30   14    0.48051    0.46949  2.29%  32.4  322s
 23354   660    0.48002   37    6    0.48051    0.46996  2.20%  32.4  328s
 24560   287    0.47630   30   10    0.48051    0.47301  1.56%  32.1  334s
 25046   131     cutoff   33         0.48051    0.47347  1.46%  32.1  337s

Cutting planes:
  Implied bound: 346
  MIR: 177
  Flow cover: 571
  RLT: 86
  Relax-and-lift: 142

Explored 25590 nodes (818867 simplex iterations) in 338.38 seconds (128.43 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.480511 0.480511 0.480511 ... 0.576718

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

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

        Job resource usage summary 

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

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