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.60961 seconds.
    local lower bound updated to 0.221235
    local upper bound updated to 0.430947

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.491665
    local lower bound updated to 0.486838

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.491665
    local lower bound updated to 0.489358

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.489862
    local lower bound updated to 0.489777

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.489777
    local lower bound updated to 0.489777
The Dual step takes 0.104724 (S)
upper bound updated to 0.489777
*** OUTER ITERATION 1 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 6.46237 seconds.
    local lower bound updated to 0.318686
    local upper bound updated to 0.442085

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 18.5249 seconds.
    local lower bound updated to 0.432456
    local upper bound updated to 0.437341

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 18.3508 seconds.
    local lower bound updated to 0.432456
    local upper bound updated to 0.436241

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 19.7767 seconds.
    local lower bound updated to 0.435278
    local upper bound updated to 0.435456

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 26.2258 seconds.
    local lower bound updated to 0.435278
    local upper bound updated to 0.435288

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.458465
    local lower bound updated to 0.451597

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.458465
    local lower bound updated to 0.453223

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.456043
    local lower bound updated to 0.45354

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 31.3101 seconds.
    local lower bound updated to 0.364839
    local upper bound updated to 0.45354

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.88146 seconds.
    local lower bound updated to 0.364839
    local upper bound updated to 0.441806

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 56.6166 seconds.
    local lower bound updated to 0.424728
    local upper bound updated to 0.439522

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 44.7678 seconds.
    local lower bound updated to 0.432241
    local upper bound updated to 0.438333

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 58.0224 seconds.
    local lower bound updated to 0.436222
    local upper bound updated to 0.437977

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 53.0955 seconds.
    local lower bound updated to 0.436289
    local upper bound updated to 0.437573

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 44.4467 seconds.
    local lower bound updated to 0.43718
    local upper bound updated to 0.437505

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 56.8645 seconds.
    local lower bound updated to 0.437357
    local upper bound updated to 0.437483

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 44.7978 seconds.
    local lower bound updated to 0.437415
    local upper bound updated to 0.437454

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 50.5668 seconds.
    local lower bound updated to 0.437445
    local upper bound updated to 0.437453
The Primal step takes 444.408 (S)
lower bound updated to 0.437445

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.44456
    local lower bound updated to 0.437607

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.443811
    local lower bound updated to 0.43782

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.437893
    local lower bound updated to 0.437821

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 53.5748 seconds.
    local lower bound updated to 0.379453
    local upper bound updated to 0.437821

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 107.82 seconds.
    local lower bound updated to 0.436448
    local upper bound updated to 0.437743

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 87.1662 seconds.
    local lower bound updated to 0.437227
    local upper bound updated to 0.437663

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 145.017 seconds.
    local lower bound updated to 0.437471
    local upper bound updated to 0.437635

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 101.688 seconds.
    local lower bound updated to 0.437523
    local upper bound updated to 0.437618

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 131.635 seconds.
    local lower bound updated to 0.437543
    local upper bound updated to 0.437616

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 148.835 seconds.
    local lower bound updated to 0.43757
    local upper bound updated to 0.437616

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 97.5932 seconds.
    local lower bound updated to 0.437596
    local upper bound updated to 0.437616

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.442556
    local lower bound updated to 0.437616

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 54.3265 seconds.
    local lower bound updated to 0.379453
    local upper bound updated to 0.437616

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 157.536 seconds.
    local lower bound updated to 0.437616
    local upper bound updated to 0.437616
The time obtain the optimal randomized assortment is 1782.73 (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_200.000000_204518.log"
The computation time limit for a lower bound is 84202
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 2216 rows, 1694 columns and 7265 nonzeros
Model fingerprint: 0x7b27536a
Variable types: 1629 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 514 rows and 908 columns
Presolve time: 0.03s
Presolved: 1702 rows, 786 columns, 6569 nonzeros
Variable types: 723 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6064413
Found heuristic solution: objective 0.5836971
Found heuristic solution: objective 0.4915476
Found heuristic solution: objective 0.4887756

Root relaxation: objective 2.223385e-01, 213 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.22234    0   23    0.48878    0.22234  54.5%     -    0s
     0     0    0.22762    0   26    0.48878    0.22762  53.4%     -    0s
H    0     0                       0.4711397    0.22762  51.7%     -    0s
     0     0    0.22762    0   26    0.47114    0.22762  51.7%     -    0s
     0     0    0.29384    0   31    0.47114    0.29384  37.6%     -    0s
H    0     0                       0.4542961    0.29384  35.3%     -    0s
     0     0    0.29393    0   30    0.45430    0.29393  35.3%     -    0s
H    0     0                       0.4427077    0.32164  27.3%     -    0s
     0     0    0.32164    0   34    0.44271    0.32164  27.3%     -    0s
     0     0    0.32290    0   34    0.44271    0.32290  27.1%     -    0s
H    0     0                       0.4387891    0.33087  24.6%     -    0s
     0     0    0.33087    0   32    0.43879    0.33087  24.6%     -    0s
     0     0    0.33148    0   31    0.43879    0.33148  24.5%     -    0s
     0     0    0.33383    0   31    0.43879    0.33383  23.9%     -    0s
H    0     0                       0.4376156    0.33383  23.7%     -    2s
     0     2    0.33383    0   31    0.43762    0.33383  23.7%     -    2s
H  206   149                       0.4376156    0.34921  20.2%  25.7    3s
H  259   166                       0.4376156    0.35501  18.9%  26.6    3s
   492   292    0.40488   14   14    0.43762    0.35883  18.0%  27.7    6s
   985   498     cutoff   20         0.43762    0.36716  16.1%  27.2   11s
H 1023   498                       0.4376156    0.36716  16.1%  27.6   11s
  2140   897    0.41615   14   36    0.43762    0.37599  14.1%  27.5   15s
  2504   990     cutoff   26         0.43762    0.37613  14.0%  29.6   20s
H 2534   944                       0.4376156    0.37800  13.6%  29.6   20s
H 2538   900                       0.4376156    0.37800  13.6%  29.7   20s
  3799   960    0.41746   22   20    0.43762    0.38638  11.7%  30.0   25s
H 4488   863                       0.4376156    0.38883  11.1%  29.5   27s
  4690   944     cutoff   25         0.43762    0.39046  10.8%  29.4   30s
  5269  1082    0.43361   23   18    0.43762    0.39240  10.3%  29.1   36s
  6182  1286 infeasible   29         0.43762    0.39504  9.73%  28.8   40s
  6967  1488    0.43721   25    4    0.43762    0.39683  9.32%  28.4   45s
H 8341  1671                       0.4376156    0.39848  8.94%  27.9   49s
  8516  1747    0.40388   21   21    0.43762    0.39886  8.86%  27.7   50s
  9523  1883     cutoff   31         0.43762    0.40019  8.55%  27.3   55s
 10956  2045     cutoff   25         0.43762    0.40177  8.19%  27.1   60s
H11000  2045                       0.4376156    0.40177  8.19%  27.1   60s
 12471  2170    0.43226   21   20    0.43762    0.40278  7.96%  26.7   65s
 13669  2295    0.42129   26   15    0.43762    0.40376  7.74%  26.5   70s
 15031  2446     cutoff   26         0.43762    0.40497  7.46%  26.2   75s
 16369  2501     cutoff   27         0.43762    0.40584  7.26%  25.9   80s
H16900  2540                       0.4376156    0.40609  7.20%  25.8   82s
 18039  2599     cutoff   28         0.43762    0.40707  6.98%  25.6   85s
 19358  2658    0.43133   29   10    0.43762    0.40787  6.80%  25.5   91s
 20341  2714    0.42678   21   21    0.43762    0.40853  6.65%  25.4   95s
 22639  2752     cutoff   30         0.43762    0.41010  6.29%  25.1  101s
 24743  2770    0.41905   25   16    0.43762    0.41162  5.94%  24.9  106s
 25975  2748    0.41978   27   14    0.43762    0.41234  5.78%  24.7  111s
 27174  2720     cutoff   33         0.43762    0.41300  5.62%  24.6  115s
 29457  2628     cutoff   27         0.43762    0.41420  5.35%  24.4  120s
 32045  2494     cutoff   25         0.43762    0.41589  4.96%  24.1  125s
 33511  2379    0.42932   28   15    0.43762    0.41674  4.77%  24.0  130s
 35480  2236     cutoff   28         0.43762    0.41776  4.54%  23.8  135s
 37469  2031     cutoff   23         0.43762    0.41922  4.20%  23.6  141s
 38766  1821    0.43134   25   12    0.43762    0.42027  3.96%  23.5  146s
 40467  1637    0.43283   24   12    0.43762    0.42145  3.69%  23.4  151s
H41429  1477                       0.4376156    0.42216  3.53%  23.3  153s
 41643  1454    0.42808   23   18    0.43762    0.42240  3.48%  23.3  155s
 43452   896    0.43587   22   20    0.43762    0.42484  2.92%  23.1  161s

Cutting planes:
  Gomory: 2
  Implied bound: 184
  MIR: 125
  Flow cover: 402
  RLT: 74
  Relax-and-lift: 38

Explored 45585 nodes (1037331 simplex iterations) in 163.93 seconds (128.95 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.437616 0.437616 0.437616 ... 0.583697

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

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

        Job resource usage summary 

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

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