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.89679 seconds.
    local lower bound updated to 0.284577
    local upper bound updated to 0.568873

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.685164
    local lower bound updated to 0.640161

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.665013
    local lower bound updated to 0.655101

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.663329
    local lower bound updated to 0.659358

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.038 seconds.
    local lower bound updated to 0.452122
    local upper bound updated to 0.638185

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.47965 seconds.
    local lower bound updated to 0.547613
    local upper bound updated to 0.584715

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 200.041 seconds.
    local lower bound updated to 0.550969
    local upper bound updated to 0.583677

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 25.058 seconds.
    local lower bound updated to 0.572156
    local upper bound updated to 0.579552

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 11.6955 seconds.
    local lower bound updated to 0.573136
    local upper bound updated to 0.578036

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 34.9889 seconds.
    local lower bound updated to 0.573136
    local upper bound updated to 0.574644

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 6.14607 seconds.
    local lower bound updated to 0.574644
    local upper bound updated to 0.574644
The Primal step takes 480.473 (S)
lower bound updated to 0.574644

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.626358
    local lower bound updated to 0.610193

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.626358
    local lower bound updated to 0.613263

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.029 seconds.
    local lower bound updated to 0.487194
    local upper bound updated to 0.612812

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 70.4961 seconds.
    local lower bound updated to 0.580222
    local upper bound updated to 0.601808

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 200.024 seconds.
    local lower bound updated to 0.592668
    local upper bound updated to 0.596424

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 31.6553 seconds.
    local lower bound updated to 0.592668
    local upper bound updated to 0.59528

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 57.3489 seconds.
    local lower bound updated to 0.592668
    local upper bound updated to 0.594173

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 73.6878 seconds.
    local lower bound updated to 0.592668
    local upper bound updated to 0.594081

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 97.883 seconds.
    local lower bound updated to 0.593753
    local upper bound updated to 0.593825

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.614217
    local lower bound updated to 0.593825

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.601218
    local lower bound updated to 0.594863

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.598677
    local lower bound updated to 0.595581

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.596965
    local lower bound updated to 0.595791

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.049 seconds.
    local lower bound updated to 0.510315
    local upper bound updated to 0.595791

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.046 seconds.
    local lower bound updated to 0.583182
    local upper bound updated to 0.595092

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 200.04 seconds.
    local lower bound updated to 0.593039
    local upper bound updated to 0.594995

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 200.043 seconds.
    local lower bound updated to 0.5935
    local upper bound updated to 0.594943

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 200.041 seconds.
    local lower bound updated to 0.5935
    local upper bound updated to 0.594762

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 200.032 seconds.
    local lower bound updated to 0.5935
    local upper bound updated to 0.594523

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 200.027 seconds.
    local lower bound updated to 0.593994
    local upper bound updated to 0.594493

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 200.03 seconds.
    local lower bound updated to 0.594297
    local upper bound updated to 0.594491

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 200.031 seconds.
    local lower bound updated to 0.594311
    local upper bound updated to 0.594457

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.610572
    local lower bound updated to 0.594457

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 200.041 seconds.
    local lower bound updated to 0.510315
    local upper bound updated to 0.594457

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 200.047 seconds.
    local lower bound updated to 0.594457
    local upper bound updated to 0.594457
The time obtain the optimal randomized assortment is 3791.85 (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_19_4_200.000000_200420.log"
The computation time limit for a lower bound is 82196
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 2364 rows, 1734 columns and 7980 nonzeros
Model fingerprint: 0x52e760c0
Variable types: 1661 continuous, 73 integer (73 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 441 rows and 825 columns
Presolve time: 0.03s
Presolved: 1923 rows, 909 columns, 7515 nonzeros
Variable types: 838 continuous, 71 integer (71 binary)
Found heuristic solution: objective 0.7599095
Found heuristic solution: objective 0.7074111

Root relaxation: objective 4.631399e-01, 267 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.46314    0   25    0.70741    0.46314  34.5%     -    0s
H    0     0                       0.7050096    0.46314  34.3%     -    0s
H    0     0                       0.7029794    0.46314  34.1%     -    0s
H    0     0                       0.7010490    0.46314  33.9%     -    0s
H    0     0                       0.6917950    0.46350  33.0%     -    0s
     0     0    0.46402    0   27    0.69180    0.46402  32.9%     -    0s
H    0     0                       0.6783719    0.46402  31.6%     -    0s
     0     0    0.49386    0   36    0.67837    0.49386  27.2%     -    0s
H    0     0                       0.6450151    0.49386  23.4%     -    0s
     0     0    0.49386    0   36    0.64502    0.49386  23.4%     -    0s
H    0     0                       0.6295998    0.49386  21.6%     -    0s
     0     0    0.50576    0   35    0.62960    0.50576  19.7%     -    0s
H    0     0                       0.6237884    0.50576  18.9%     -    0s
H    0     0                       0.6188332    0.50757  18.0%     -    0s
H    0     0                       0.5960314    0.50757  14.8%     -    0s
     0     0    0.50757    0   37    0.59603    0.50757  14.8%     -    0s
     0     0    0.51085    0   38    0.59603    0.51085  14.3%     -    0s
     0     0    0.51156    0   37    0.59603    0.51156  14.2%     -    0s
     0     0    0.51264    0   37    0.59603    0.51264  14.0%     -    0s
H    0     0                       0.5955760    0.51264  13.9%     -    0s
H    0     0                       0.5944567    0.51264  13.8%     -    3s
     0     2    0.51264    0   37    0.59446    0.51264  13.8%     -    3s
   386   207    0.55240   12   19    0.59446    0.52241  12.1%  26.6    5s
  1118   602    0.54738    8   40    0.59446    0.53547  9.92%  25.0   11s
  1422   734    0.54776   19   31    0.59446    0.53547  9.92%  29.7   15s
  2299   752    0.54857   21   21    0.59446    0.54512  8.30%  31.2   20s
  3327   977    0.56490   23   20    0.59446    0.54944  7.57%  30.5   25s
  4171  1197    0.56591   22   23    0.59446    0.55179  7.18%  29.2   30s
  4774  1393    0.58364   35   12    0.59446    0.55301  6.97%  29.1   35s
  5287  1577    0.59001   26   18    0.59446    0.55419  6.77%  29.0   40s
  6137  1814    0.57588   25   21    0.59446    0.55535  6.58%  28.7   47s
  6923  2052    0.58044   31   14    0.59446    0.55651  6.38%  28.2   50s
  7674  2209    0.58371   24   27    0.59446    0.55755  6.21%  27.8   55s
  8140  2391    0.58021   27   17    0.59446    0.55797  6.14%  27.7   61s
  8852  2553    0.59104   32   12    0.59446    0.55837  6.07%  27.3   65s
  9890  2821    0.57473   27   20    0.59446    0.55954  5.87%  27.1   72s
 10293  2898    0.56566   24   26    0.59446    0.55978  5.83%  26.8   75s
 10574  3134     cutoff   35         0.59446    0.55998  5.80%  26.8   80s
 12309  3264    0.57755   25   20    0.59446    0.56103  5.62%  26.4   85s
 13256  3493     cutoff   31         0.59446    0.56187  5.48%  26.3   90s
 14887  3699    0.58216   29   19    0.59446    0.56308  5.28%  25.9   96s
 15935  3959    0.57992   26   22    0.59446    0.56369  5.17%  25.7  100s
 17722  4195 infeasible   28         0.59446    0.56462  5.02%  25.5  106s
 18681  4340 infeasible   27         0.59446    0.56495  4.96%  25.4  110s
 20258  4547    0.58838   27   16    0.59446    0.56572  4.83%  25.1  115s
 21449  4719    0.59047   29   12    0.59446    0.56621  4.75%  25.1  120s
 23317  4958    0.58499   29   12    0.59446    0.56707  4.61%  25.0  126s
 24716  5127    0.58016   28   16    0.59446    0.56765  4.51%  24.9  132s
 26148  5226     cutoff   30         0.59446    0.56797  4.46%  24.8  135s
 27833  5406    0.58763   26   19    0.59446    0.56873  4.33%  24.7  140s
 30546  5517     cutoff   31         0.59446    0.56952  4.20%  24.5  145s
 32575  5631 infeasible   29         0.59446    0.57020  4.08%  24.4  151s
H33123  5647                       0.5944567    0.57031  4.06%  24.3  153s
H33246  5647                       0.5944567    0.57037  4.05%  24.3  153s
 33751  5695     cutoff   30         0.59446    0.57050  4.03%  24.2  156s
 34863  5727    0.58555   28   12    0.59446    0.57092  3.96%  24.2  160s
 36330  5776     cutoff   31         0.59446    0.57126  3.90%  24.1  165s
 39008  5860    0.58437   29   18    0.59446    0.57200  3.78%  23.9  172s
 39583  5866    0.58971   29   17    0.59446    0.57217  3.75%  23.8  176s
 41483  5929     cutoff   31         0.59446    0.57254  3.69%  23.7  181s
 43069  5943     cutoff   30         0.59446    0.57295  3.62%  23.7  187s
 45456  5955    0.57949   24   23    0.59446    0.57360  3.51%  23.6  190s
 46833  5925    0.59264   31   14    0.59446    0.57394  3.45%  23.5  195s
 48752  5886     cutoff   32         0.59446    0.57438  3.38%  23.5  201s
 49925  5873     cutoff   29         0.59446    0.57454  3.35%  23.4  205s
 51610  5808    0.59114   26   17    0.59446    0.57498  3.28%  23.3  210s
 53681  5730    0.59242   28   17    0.59446    0.57566  3.16%  23.3  215s
 56019  5564    0.58777   32   13    0.59446    0.57620  3.07%  23.2  220s
 59190  5422     cutoff   29         0.59446    0.57692  2.95%  23.1  226s
 60195  5328    0.59128   29   17    0.59446    0.57724  2.90%  23.1  231s
 61247  5253    0.58171   28   14    0.59446    0.57747  2.86%  23.0  235s
 63734  4987     cutoff   32         0.59446    0.57812  2.75%  23.0  240s
 67205  4665     cutoff   30         0.59446    0.57900  2.60%  22.8  245s
 69653  4388     cutoff   28         0.59446    0.57966  2.49%  22.8  250s
 71818  4102     cutoff   32         0.59446    0.58023  2.39%  22.7  255s
 73667  3840     cutoff   29         0.59446    0.58085  2.29%  22.6  261s
 75879  3530    0.58886   25   22    0.59446    0.58154  2.17%  22.6  265s
 77984  2980     cutoff   29         0.59446    0.58228  2.05%  22.5  271s
 80320  2352    0.59326   36    6    0.59446    0.58381  1.79%  22.4  276s
 82085  1782     cutoff   30         0.59446    0.58473  1.64%  22.4  281s
 85298   150 infeasible   28         0.59446    0.58888  0.94%  22.2  285s

Cutting planes:
  Gomory: 1
  Implied bound: 205
  MIR: 137
  Flow cover: 402
  RLT: 49
  Relax-and-lift: 54

Explored 85996 nodes (1904401 simplex iterations) in 285.31 seconds (305.81 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.594457 0.594457 0.594457 ... 0.678372

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

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

        Job resource usage summary 

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

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