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.22416 seconds.
    local lower bound updated to 0.182949
    local upper bound updated to 0.362472

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.496188
    local lower bound updated to 0.493306

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 9.27086 seconds.
    local lower bound updated to 0.245105
    local upper bound updated to 0.37739

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 8.64054 seconds.
    local lower bound updated to 0.354178
    local upper bound updated to 0.362402

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 6.00181 seconds.
    local lower bound updated to 0.357489
    local upper bound updated to 0.360382

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 10.2213 seconds.
    local lower bound updated to 0.359353
    local upper bound updated to 0.35936
The Primal step takes 34.1423 (S)
lower bound updated to 0.359353

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.413405
    local lower bound updated to 0.35936

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.390085
    local lower bound updated to 0.368668

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.389054
    local lower bound updated to 0.376827

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.382827
    local lower bound updated to 0.377371

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.382827
    local lower bound updated to 0.379577

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.382827
    local lower bound updated to 0.381738

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 20.4884 seconds.
    local lower bound updated to 0.304056
    local upper bound updated to 0.368235

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 12.7589 seconds.
    local lower bound updated to 0.339826
    local upper bound updated to 0.36697

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0192 seconds.
    local lower bound updated to 0.352121
    local upper bound updated to 0.362373

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 36.9468 seconds.
    local lower bound updated to 0.361283
    local upper bound updated to 0.36184

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.019 seconds.
    local lower bound updated to 0.361283
    local upper bound updated to 0.361807

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 23.4828 seconds.
    local lower bound updated to 0.361283
    local upper bound updated to 0.361803

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0157 seconds.
    local lower bound updated to 0.361356
    local upper bound updated to 0.361749

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 45.1099 seconds.
    local lower bound updated to 0.361605
    local upper bound updated to 0.361712

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 48.0581 seconds.
    local lower bound updated to 0.361712
    local upper bound updated to 0.361712
The Primal step takes 366.937 (S)
lower bound updated to 0.361712

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.39097
    local lower bound updated to 0.361783

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.381186
    local lower bound updated to 0.376087

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.381186
    local lower bound updated to 0.376395

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.379934
    local lower bound updated to 0.37665

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.379934
    local lower bound updated to 0.376856

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 28.4302 seconds.
    local lower bound updated to 0.329925
    local upper bound updated to 0.376856

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 6.43085 seconds.
    local lower bound updated to 0.330357
    local upper bound updated to 0.374504

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0274 seconds.
    local lower bound updated to 0.369742
    local upper bound updated to 0.374145

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0189 seconds.
    local lower bound updated to 0.370752
    local upper bound updated to 0.373392

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0215 seconds.
    local lower bound updated to 0.370752
    local upper bound updated to 0.373392

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0233 seconds.
    local lower bound updated to 0.372075
    local upper bound updated to 0.373288

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0214 seconds.
    local lower bound updated to 0.373066
    local upper bound updated to 0.373228

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0275 seconds.
    local lower bound updated to 0.373228
    local upper bound updated to 0.373228
The Primal step takes 395.052 (S)
lower bound updated to 0.373228

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.411398
    local lower bound updated to 0.373278

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.381786
    local lower bound updated to 0.373511

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 42.6603 seconds.
    local lower bound updated to 0.331627
    local upper bound updated to 0.373511

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.027 seconds.
    local lower bound updated to 0.3705
    local upper bound updated to 0.373431

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0261 seconds.
    local lower bound updated to 0.372993
    local upper bound updated to 0.373283

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.027 seconds.
    local lower bound updated to 0.373118
    local upper bound updated to 0.373278

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.408286
    local lower bound updated to 0.373278

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.3775
    local lower bound updated to 0.373356

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.373952
    local lower bound updated to 0.373369

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.37337
    local lower bound updated to 0.373369
The Dual step takes 25.439 (S)
upper bound updated to 0.37337
*** OUTER ITERATION 5 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 48.0413 seconds.
    local lower bound updated to 0.334191
    local upper bound updated to 0.373369

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.411527
    local lower bound updated to 0.373369

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.036 seconds.
    local lower bound updated to 0.334191
    local upper bound updated to 0.373369

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0348 seconds.
    local lower bound updated to 0.373369
    local upper bound updated to 0.373369
The time obtain the optimal randomized assortment is 1379.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_60.000000_64318.log"
The computation time limit for a lower bound is 84608
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 3768 rows, 2878 columns and 12235 nonzeros
Model fingerprint: 0x9b608615
Variable types: 2813 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [1e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 885 rows and 1621 columns
Presolve time: 0.05s
Presolved: 2883 rows, 1257 columns, 11142 nonzeros
Variable types: 1193 continuous, 64 integer (64 binary)
Found heuristic solution: objective 0.5721767
Found heuristic solution: objective 0.4864549

Root relaxation: objective 1.978465e-01, 299 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.19785    0   24    0.48645    0.19785  59.3%     -    0s
H    0     0                       0.4722212    0.19785  58.1%     -    0s
     0     0    0.20227    0   28    0.47222    0.20227  57.2%     -    0s
H    0     0                       0.4176901    0.20227  51.6%     -    0s
     0     0    0.20227    0   28    0.41769    0.20227  51.6%     -    0s
     0     0    0.25658    0   31    0.41769    0.25658  38.6%     -    0s
H    0     0                       0.3767091    0.25658  31.9%     -    0s
     0     0    0.26180    0   31    0.37671    0.26180  30.5%     -    0s
     0     0    0.27558    0   33    0.37671    0.27558  26.8%     -    0s
     0     0    0.27591    0   31    0.37671    0.27591  26.8%     -    0s
     0     0    0.28057    0   33    0.37671    0.28057  25.5%     -    1s
     0     0    0.28093    0   34    0.37671    0.28093  25.4%     -    1s
     0     0    0.28226    0   33    0.37671    0.28226  25.1%     -    1s
H    0     0                       0.3733694    0.28226  24.4%     -    5s
     0     2    0.28226    0   33    0.37337    0.28226  24.4%     -    5s
H  268   160                       0.3733694    0.29192  21.8%  53.2    7s
   716   278    0.35986   10   17    0.37337    0.30701  17.8%  55.4   10s
  1190   407    0.33999   11   19    0.37337    0.31420  15.8%  54.6   15s
  2280   672    0.35520    9   24    0.37337    0.32309  13.5%  51.3   20s
  2481   705    0.36849   13   10    0.37337    0.32470  13.0%  50.4   25s
  2950   773    0.35205    8   25    0.37337    0.32639  12.6%  48.6   30s
  3371   841    0.37025   10   23    0.37337    0.32851  12.0%  47.8   35s
  4002   956    0.34093   11   14    0.37337    0.33055  11.5%  46.2   40s
  4357   960    0.34985   12   37    0.37337    0.33127  11.3%  45.5   45s
  4403   999    0.33127   16   26    0.37337    0.33127  11.3%  47.1   50s
H 4831   965                       0.3733694    0.33127  11.3%  47.0   54s
  4996   965    0.35482   22   19    0.37337    0.33127  11.3%  47.7   57s
H 5297   871                       0.3733694    0.33127  11.3%  47.5   58s
H 5305   824                       0.3733694    0.33127  11.3%  47.4   58s
  5887   752    0.37042   25   12    0.37337    0.33127  11.3%  46.6   60s
  6578   608     cutoff   24         0.37337    0.33330  10.7%  45.9   66s
  7194   582    0.36894   23   16    0.37337    0.33582  10.1%  45.6   71s
  7661   649    0.37190   28    8    0.37337    0.33764  9.57%  45.3   75s
  8245   714     cutoff   25         0.37337    0.33940  9.10%  45.2   80s
  9036   749     cutoff   29         0.37337    0.34136  8.57%  44.9   86s
  9588   795    0.35547   24   20    0.37337    0.34235  8.31%  44.6   91s
  9983   823    0.35147   21   12    0.37337    0.34327  8.06%  44.5   97s
 10344   844    0.35679   22   20    0.37337    0.34378  7.92%  44.4  101s
 11272   848     cutoff   25         0.37337    0.34529  7.52%  43.7  106s
 11601   868     cutoff   26         0.37337    0.34567  7.42%  43.6  110s
 13009   858     cutoff   24         0.37337    0.34780  6.85%  43.0  115s
 14190   842     cutoff   27         0.37337    0.34955  6.38%  42.4  123s
 14793   829     cutoff   27         0.37337    0.35008  6.24%  42.1  127s
 15273   825    0.36812   26   16    0.37337    0.35132  5.90%  42.0  130s
 15997   741     cutoff   24         0.37337    0.35233  5.64%  41.9  135s
 16863   677 infeasible   25         0.37337    0.35335  5.36%  41.8  141s
 17947   578     cutoff   27         0.37337    0.35624  4.59%  41.3  147s
 18915   399     cutoff   26         0.37337    0.35747  4.26%  41.1  153s
 19643   278    0.36838   26   10    0.37337    0.35953  3.71%  40.9  156s

Cutting planes:
  Gomory: 1
  Implied bound: 352
  MIR: 230
  Flow cover: 558
  RLT: 78
  Relax-and-lift: 121

Explored 20796 nodes (843187 simplex iterations) in 159.90 seconds (126.08 work units)
Thread count was 4 (of 256 available processors)

Solution count 8: 0.373369 0.373369 0.373369 ... 0.572177

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

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

        Job resource usage summary 

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

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