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.59082 seconds.
    local lower bound updated to 0.305107
    local upper bound updated to 0.586446

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.689272
    local lower bound updated to 0.679718

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.689272
    local lower bound updated to 0.689131

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 15.6913 seconds.
    local lower bound updated to 0.452125
    local upper bound updated to 0.60996

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 12.6291 seconds.
    local lower bound updated to 0.601798
    local upper bound updated to 0.606654

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 14.701 seconds.
    local lower bound updated to 0.601798
    local upper bound updated to 0.60628

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 23.4655 seconds.
    local lower bound updated to 0.603405
    local upper bound updated to 0.604737

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 17.7281 seconds.
    local lower bound updated to 0.603553
    local upper bound updated to 0.604037

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 18.4958 seconds.
    local lower bound updated to 0.603757
    local upper bound updated to 0.604015

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 20.4965 seconds.
    local lower bound updated to 0.603995
    local upper bound updated to 0.604012

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.641501
    local lower bound updated to 0.617483

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.641501
    local lower bound updated to 0.619264

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 40.9238 seconds.
    local lower bound updated to 0.504085
    local upper bound updated to 0.61907

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 13.9776 seconds.
    local lower bound updated to 0.603136
    local upper bound updated to 0.615309

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0133 seconds.
    local lower bound updated to 0.610027
    local upper bound updated to 0.614667

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0185 seconds.
    local lower bound updated to 0.613194
    local upper bound updated to 0.614123

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0163 seconds.
    local lower bound updated to 0.613194
    local upper bound updated to 0.613634

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

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0131 seconds.
    local lower bound updated to 0.613194
    local upper bound updated to 0.613498

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0135 seconds.
    local lower bound updated to 0.613194
    local upper bound updated to 0.613347

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.613194
    local upper bound updated to 0.613346

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.613223
    local upper bound updated to 0.613344

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0197 seconds.
    local lower bound updated to 0.613223
    local upper bound updated to 0.613339

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0218 seconds.
    local lower bound updated to 0.613339
    local upper bound updated to 0.613339
The Primal step takes 655.09 (S)
lower bound updated to 0.613339

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.63871
    local lower bound updated to 0.617915

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0118 seconds.
    local lower bound updated to 0.614381
    local upper bound updated to 0.615752

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.014 seconds.
    local lower bound updated to 0.614381
    local upper bound updated to 0.615606

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0182 seconds.
    local lower bound updated to 0.614958
    local upper bound updated to 0.615536

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0174 seconds.
    local lower bound updated to 0.61521
    local upper bound updated to 0.615428

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0149 seconds.
    local lower bound updated to 0.6153
    local upper bound updated to 0.615417

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0185 seconds.
    local lower bound updated to 0.6153
    local upper bound updated to 0.615377

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0129 seconds.
    local lower bound updated to 0.615335
    local upper bound updated to 0.615375

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.012 seconds.
    local lower bound updated to 0.615335
    local upper bound updated to 0.615355

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0144 seconds.
    local lower bound updated to 0.615355
    local upper bound updated to 0.615355
The Primal step takes 600.176 (S)
lower bound updated to 0.615355

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.628871
    local lower bound updated to 0.615778

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0168 seconds.
    local lower bound updated to 0.527389
    local upper bound updated to 0.615778

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.615712
    local upper bound updated to 0.615768

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.627569
    local lower bound updated to 0.615768

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0146 seconds.
    local lower bound updated to 0.527389
    local upper bound updated to 0.615768

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0146 seconds.
    local lower bound updated to 0.615768
    local upper bound updated to 0.615768
The time obtain the optimal randomized assortment is 1705.3 (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_65618.log"
The computation time limit for a lower bound is 84288
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 1634 rows, 1250 columns and 5407 nonzeros
Model fingerprint: 0x93ca8e70
Variable types: 1185 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [7e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 361 rows and 651 columns
Presolve time: 0.01s
Presolved: 1273 rows, 599 columns, 4908 nonzeros
Variable types: 537 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.7104579
Found heuristic solution: objective 0.6891418

Root relaxation: objective 4.910335e-01, 146 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.49103    0   14    0.68914    0.49103  28.7%     -    0s
H    0     0                       0.6254151    0.49103  21.5%     -    0s
     0     0    0.49390    0   20    0.62542    0.49390  21.0%     -    0s
     0     0    0.52628    0   30    0.62542    0.52628  15.9%     -    0s
     0     0    0.52687    0   28    0.62542    0.52687  15.8%     -    0s
H    0     0                       0.6251748    0.53683  14.1%     -    0s
     0     0    0.53683    0   32    0.62517    0.53683  14.1%     -    0s
H    0     0                       0.6187986    0.53772  13.1%     -    0s
     0     0    0.53772    0   28    0.61880    0.53772  13.1%     -    0s
H    0     0                       0.6171914    0.54430  11.8%     -    0s
     0     0    0.54430    0   31    0.61719    0.54430  11.8%     -    0s
H    0     0                       0.6170709    0.54462  11.7%     -    0s
     0     0    0.54462    0   31    0.61707    0.54462  11.7%     -    0s
H    0     0                       0.6165202    0.54462  11.7%     -    0s
     0     0    0.54780    0   31    0.61652    0.54780  11.1%     -    0s
H    0     0                       0.6157721    0.54780  11.0%     -    1s
     0     2    0.54780    0   31    0.61577    0.54780  11.0%     -    1s
*   93    96              19       0.6157678    0.54987  10.7%  18.4    1s
H  463   295                       0.6157678    0.55250  10.3%  15.9    1s
  1724   700    0.60560   26   10    0.61577    0.56695  7.93%  18.3    5s
  3466  1106     cutoff   30         0.61577    0.57473  6.66%  19.4   10s
  6110  2067    0.60994   28   12    0.61577    0.57901  5.97%  19.4   15s
H 6502  2082                       0.6157678    0.57961  5.87%  19.4   15s
H 7200  2295                       0.6157678    0.58068  5.70%  19.4   17s
  8222  2658    0.60383   28   12    0.61577    0.58169  5.53%  19.4   20s
H 8379  2658                       0.6157678    0.58172  5.53%  19.3   20s
H 8838  2735                       0.6157678    0.58228  5.44%  19.3   20s
 10315  3259    0.60470   29   16    0.61577    0.58351  5.24%  19.2   25s
 13648  3968     cutoff   29         0.61577    0.58560  4.90%  19.0   30s
H13739  3968                       0.6157678    0.58560  4.90%  19.0   30s
 17160  4577    0.60287   30   14    0.61577    0.58755  4.58%  18.7   35s
 20610  5153     cutoff   35         0.61577    0.58867  4.40%  18.5   40s
 24338  5719    0.61177   33    8    0.61577    0.59026  4.14%  18.2   45s
 28042  6229    0.60767   29   12    0.61577    0.59132  3.97%  18.0   50s
 31404  6630    0.60780   37   10    0.61577    0.59226  3.82%  17.9   56s
 35697  6908    0.60124   27   14    0.61577    0.59351  3.61%  17.8   60s
 38536  7139     cutoff   29         0.61577    0.59418  3.51%  17.7   65s
 42813  7337    0.61354   34   10    0.61577    0.59524  3.33%  17.6   70s
 46073  7486     cutoff   30         0.61577    0.59589  3.23%  17.5   75s
 49822  7678    0.60457   34   12    0.61577    0.59676  3.09%  17.4   80s
 52389  7744     cutoff   29         0.61577    0.59732  3.00%  17.3   86s
 56953  7798    0.60146   30   16    0.61577    0.59817  2.86%  17.2   90s
 61890  7760    0.61400   35    8    0.61577    0.59900  2.72%  17.1   95s
 65284  7672     cutoff   35         0.61577    0.59971  2.61%  17.1  100s
 68941  7588 infeasible   32         0.61577    0.60027  2.52%  17.0  105s
 72878  7383    0.60172   28   14    0.61577    0.60095  2.41%  16.9  110s
 76418  7166    0.61066   29   12    0.61577    0.60172  2.28%  16.8  115s
 81441  6783    0.61053   30   12    0.61577    0.60247  2.16%  16.8  120s
 86126  6314     cutoff   26         0.61577    0.60347  2.00%  16.7  125s
 90007  5769    0.60554   29   14    0.61577    0.60424  1.87%  16.6  130s
 96361  4825     cutoff   35         0.61577    0.60561  1.65%  16.5  135s
H96603  4785                       0.6157678    0.60572  1.63%  16.5  136s
H96648  4785                       0.6157678    0.60573  1.63%  16.5  136s
 99524  4084     cutoff   42         0.61577    0.60657  1.49%  16.5  140s
H104400  2732                       0.6157678    0.60832  1.21%  16.4  144s
 105425  2365     cutoff   37         0.61577    0.60877  1.14%  16.4  145s

Cutting planes:
  Implied bound: 139
  MIR: 101
  Flow cover: 291
  RLT: 52
  Relax-and-lift: 36

Explored 110550 nodes (1794411 simplex iterations) in 148.14 seconds (189.69 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.615768 0.615768 0.615768 ... 0.625415

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

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

        Job resource usage summary 

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

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