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 4.05653 seconds.
    local lower bound updated to 0.290219
    local upper bound updated to 0.563946

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.642616
    local lower bound updated to 0.639663

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.642616
    local lower bound updated to 0.640911

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 44.9773 seconds.
    local lower bound updated to 0.417599
    local upper bound updated to 0.580449

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 29.7165 seconds.
    local lower bound updated to 0.564594
    local upper bound updated to 0.575096

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 26.2818 seconds.
    local lower bound updated to 0.5653
    local upper bound updated to 0.573395

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 34.2443 seconds.
    local lower bound updated to 0.5653
    local upper bound updated to 0.571364

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 45.9981 seconds.
    local lower bound updated to 0.566658
    local upper bound updated to 0.56802

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 54.2292 seconds.
    local lower bound updated to 0.566658
    local upper bound updated to 0.567282

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.616789
    local lower bound updated to 0.593901

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.610899
    local lower bound updated to 0.601254

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.609957
    local lower bound updated to 0.601983

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.606043
    local lower bound updated to 0.602033

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.602033
    local lower bound updated to 0.602033
The Dual step takes 2.4117 (S)
upper bound updated to 0.602033
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.492679
    local upper bound updated to 0.593911

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 18.6893 seconds.
    local lower bound updated to 0.549009
    local upper bound updated to 0.586608

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.572074
    local upper bound updated to 0.582774

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.572074
    local upper bound updated to 0.580821

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.032 seconds.
    local lower bound updated to 0.573401
    local upper bound updated to 0.58025

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.016 seconds.
    local lower bound updated to 0.575103
    local upper bound updated to 0.580145

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.575103
    local upper bound updated to 0.579365

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.016 seconds.
    local lower bound updated to 0.576068
    local upper bound updated to 0.578123

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.576068
    local upper bound updated to 0.577979

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.015 seconds.
    local lower bound updated to 0.577188
    local upper bound updated to 0.577971

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.037 seconds.
    local lower bound updated to 0.577615
    local upper bound updated to 0.577933

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.577754
    local upper bound updated to 0.577901

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.577754
    local upper bound updated to 0.577864

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.577864
    local upper bound updated to 0.577864
The Primal step takes 1319.01 (S)
lower bound updated to 0.577864

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.613026
    local lower bound updated to 0.592681

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.596749
    local lower bound updated to 0.593419

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.594566
    local lower bound updated to 0.59354

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.026 seconds.
    local lower bound updated to 0.516699
    local upper bound updated to 0.592979

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.562297
    local upper bound updated to 0.592114

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.024 seconds.
    local lower bound updated to 0.578875
    local upper bound updated to 0.587673

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.582402
    local upper bound updated to 0.586843

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.033 seconds.
    local lower bound updated to 0.582402
    local upper bound updated to 0.586701

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.582402
    local upper bound updated to 0.586434

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.582402
    local upper bound updated to 0.586323

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.585388
    local upper bound updated to 0.586002

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.585388
    local upper bound updated to 0.585923

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.585388
    local upper bound updated to 0.585808

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.024 seconds.
    local lower bound updated to 0.585388
    local upper bound updated to 0.585798

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.026 seconds.
    local lower bound updated to 0.585392
    local upper bound updated to 0.585765

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.58548
    local upper bound updated to 0.585754

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.585711
    local upper bound updated to 0.585751

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.585718
    local upper bound updated to 0.58575

    ++ RESTRICTED PRIMAL ITERATION 16 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.585718
    local upper bound updated to 0.585746

    ++ RESTRICTED PRIMAL ITERATION 17 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.585746
    local upper bound updated to 0.585746
The Primal step takes 1700.47 (S)
lower bound updated to 0.585746

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.605469
    local lower bound updated to 0.586996

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.526105
    local upper bound updated to 0.586996

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.026 seconds.
    local lower bound updated to 0.585914
    local upper bound updated to 0.586737

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.039 seconds.
    local lower bound updated to 0.58666
    local upper bound updated to 0.586733

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.58672
    local upper bound updated to 0.586733

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.602587
    local lower bound updated to 0.586733

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.587351
    local lower bound updated to 0.586733

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.033 seconds.
    local lower bound updated to 0.530305
    local upper bound updated to 0.586733

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.586733
    local upper bound updated to 0.586733
The time obtain the optimal randomized assortment is 4047.9 (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_100.000000_107220.log"
The computation time limit for a lower bound is 81901
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 3048 rows, 2232 columns and 10316 nonzeros
Model fingerprint: 0x31134d63
Variable types: 2159 continuous, 73 integer (73 binary)
Coefficient statistics:
  Matrix range     [2e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 588 rows and 1096 columns
Presolve time: 0.02s
Presolved: 2460 rows, 1136 columns, 9666 nonzeros
Variable types: 1064 continuous, 72 integer (72 binary)
Found heuristic solution: objective 0.7375458
Found heuristic solution: objective 0.7220017
Found heuristic solution: objective 0.6936231

Root relaxation: objective 4.275412e-01, 243 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.42754    0   24    0.69362    0.42754  38.4%     -    0s
H    0     0                       0.6876144    0.42754  37.8%     -    0s
     0     0    0.42754    0   28    0.68761    0.42754  37.8%     -    0s
H    0     0                       0.6843478    0.42754  37.5%     -    0s
H    0     0                       0.6685692    0.46200  30.9%     -    0s
H    0     0                       0.6327432    0.46200  27.0%     -    0s
H    0     0                       0.6050531    0.46200  23.6%     -    0s
     0     0    0.46200    0   33    0.60505    0.46200  23.6%     -    0s
     0     0    0.46954    0   32    0.60505    0.46954  22.4%     -    0s
H    0     0                       0.6044297    0.46954  22.3%     -    0s
H    0     0                       0.5997806    0.46954  21.7%     -    0s
     0     0    0.48112    0   37    0.59978    0.48112  19.8%     -    0s
     0     0    0.48439    0   34    0.59978    0.48439  19.2%     -    0s
     0     0    0.49081    0   37    0.59978    0.49081  18.2%     -    0s
     0     0    0.49141    0   36    0.59978    0.49141  18.1%     -    0s
     0     0    0.49618    0   31    0.59978    0.49618  17.3%     -    0s
H    0     0                       0.5867328    0.49618  15.4%     -    2s
     0     2    0.49618    0   31    0.58673    0.49618  15.4%     -    2s
   296   222    0.54825   19   12    0.58673    0.50167  14.5%  33.5    5s
  2070   842    0.52737   12   30    0.58673    0.52037  11.3%  27.2   10s
H 2089   811                       0.5867328    0.52037  11.3%  26.9   12s
  2407   893    0.58564   29    6    0.58673    0.52187  11.1%  31.3   15s
  3098   936    0.54744   24   16    0.58673    0.52890  9.86%  32.6   21s
  3324   954     cutoff   31         0.58673    0.53009  9.65%  33.1   25s
  3774   960     cutoff   27         0.58673    0.53207  9.32%  33.4   30s
  4181   927    0.54983   24   19    0.58673    0.53333  9.10%  33.7   35s
  4555   946    0.56246   26   19    0.58673    0.53491  8.83%  33.9   40s
  5115  1029    0.54441   24   21    0.58673    0.53609  8.63%  34.3   45s
  5934  1248 infeasible   25         0.58673    0.53746  8.40%  34.5   50s
  7384  1538    0.56327   23   16    0.58673    0.53977  8.00%  34.3   55s
  8774  1870 infeasible   28         0.58673    0.54161  7.69%  34.9   61s
H 9076  1870                       0.5867328    0.54184  7.65%  34.8   62s
 10142  2143    0.57607   24   12    0.58673    0.54285  7.48%  34.8   66s
 11622  2452    0.55250   23   18    0.58673    0.54384  7.31%  34.7   70s
 13198  2645    0.56239   27   14    0.58673    0.54522  7.08%  34.5   76s
 14065  2864    0.57560   25   12    0.58673    0.54578  6.98%  34.4   81s
 14948  2959    0.57587   22   20    0.58673    0.54645  6.87%  34.6   85s
 16485  3176    0.58387   30    4    0.58673    0.54711  6.75%  34.6   91s
 17070  3283    0.55621   25   16    0.58673    0.54751  6.68%  34.7   95s
 18901  3579    0.57403   27   16    0.58673    0.54834  6.54%  34.5  101s
 19755  3645    0.56301   26   10    0.58673    0.54876  6.47%  34.6  105s
 20673  3746    0.58227   26   13    0.58673    0.54934  6.37%  34.7  111s
 22158  3953    0.56483   25   17    0.58673    0.55019  6.23%  34.8  116s
 23096  4044 infeasible   25         0.58673    0.55058  6.16%  34.8  121s
 24030  4116     cutoff   30         0.58673    0.55102  6.09%  34.9  125s
 25400  4233    0.55530   24   19    0.58673    0.55135  6.03%  34.8  131s
 26964  4462    0.56372   27   14    0.58673    0.55214  5.90%  34.8  136s
 28189  4542    0.57625   30   14    0.58673    0.55261  5.82%  34.7  140s
 29307  4669     cutoff   31         0.58673    0.55285  5.77%  34.7  146s
 31375  4781     cutoff   32         0.58673    0.55371  5.63%  34.7  151s
 31416  5040    0.55570   22   22    0.58673    0.55372  5.63%  34.7  162s
 36049  5107    0.58077   29   10    0.58673    0.55483  5.44%  34.6  166s
 37177  5177    0.56064   22   23    0.58673    0.55519  5.38%  34.6  171s
 38632  5243    0.56881   27   18    0.58673    0.55558  5.31%  34.7  177s
 39863  5295     cutoff   29         0.58673    0.55595  5.25%  34.7  180s
 40540  5327    0.57113   28   16    0.58673    0.55623  5.20%  34.7  185s
 41927  5408    0.56775   25   16    0.58673    0.55660  5.14%  34.8  191s
 43415  5478     cutoff   29         0.58673    0.55708  5.05%  34.8  196s
 44629  5492     cutoff   26         0.58673    0.55745  4.99%  34.7  200s
 45936  5533    0.56767   24   20    0.58673    0.55776  4.94%  34.8  207s
 47282  5589    0.57398   29   17    0.58673    0.55814  4.87%  34.8  210s
 48539  5637    0.57162   28    8    0.58673    0.55840  4.83%  34.8  216s
 49793  5683    0.58175   28   12    0.58673    0.55875  4.77%  34.8  220s
 50530  5704     cutoff   28         0.58673    0.55891  4.74%  34.8  225s
 52280  5739     cutoff   31         0.58673    0.55940  4.66%  34.8  231s
 53506  5744 infeasible   32         0.58673    0.55962  4.62%  34.8  236s
 54913  5765    0.57333   29   19    0.58673    0.56012  4.54%  34.8  240s
 56180  5779     cutoff   28         0.58673    0.56041  4.49%  34.9  245s
 57598  5814    0.57840   31   17    0.58673    0.56069  4.44%  34.9  250s
 58349  5825    0.58064   30   10    0.58673    0.56090  4.40%  34.9  256s
 59874  5841     cutoff   26         0.58673    0.56126  4.34%  34.9  262s
 60577  5840 infeasible   31         0.58673    0.56143  4.31%  34.9  265s
 61488  5871    0.58126   31   12    0.58673    0.56153  4.30%  35.0  270s
 62538  5854    0.57701   32    8    0.58673    0.56180  4.25%  35.0  275s
 63847  5851    0.58201   28   16    0.58673    0.56194  4.23%  35.0  280s
 65590  5843    0.56959   28   16    0.58673    0.56249  4.13%  35.1  286s
 66713  5806    0.58189   31   16    0.58673    0.56279  4.08%  35.1  290s
 68675  5750    0.56603   30   14    0.58673    0.56326  4.00%  35.1  296s
 69729  5712     cutoff   28         0.58673    0.56355  3.95%  35.1  300s
 71520  5669     cutoff   28         0.58673    0.56384  3.90%  35.2  306s
 72824  5639    0.57923   27   12    0.58673    0.56422  3.84%  35.2  311s
 74167  5573     cutoff   30         0.58673    0.56446  3.80%  35.2  315s
 76447  5498    0.57520   28   12    0.58673    0.56495  3.71%  35.2  321s
 77476  5438     cutoff   28         0.58673    0.56528  3.66%  35.2  325s
 78835  5388 infeasible   26         0.58673    0.56561  3.60%  35.3  331s
 80105  5308     cutoff   32         0.58673    0.56578  3.57%  35.3  335s
 81495  5241     cutoff   27         0.58673    0.56613  3.51%  35.3  341s
 82146  5177    0.57774   28   14    0.58673    0.56626  3.49%  35.3  345s
 84306  5026 infeasible   32         0.58673    0.56679  3.40%  35.3  351s
 85488  4971    0.57918   26   18    0.58673    0.56712  3.34%  35.3  355s
 87286  4851    0.58561   26   10    0.58673    0.56748  3.28%  35.3  360s
 88634  4683    0.57595   25   21    0.58673    0.56793  3.21%  35.3  366s
 89941  4602     cutoff   30         0.58673    0.56823  3.15%  35.3  370s
 92614  4298     cutoff   27         0.58673    0.56896  3.03%  35.3  376s
 94466  4092    0.58165   25   18    0.58673    0.56959  2.92%  35.3  381s
 95707  3902     cutoff   30         0.58673    0.56996  2.86%  35.3  385s
 98060  3636     cutoff   28         0.58673    0.57083  2.71%  35.3  391s
 99121  3485     cutoff   26         0.58673    0.57117  2.65%  35.3  396s
H99656  3485                       0.5867328    0.57148  2.60%  35.3  396s
 100054  3366    0.58271   28   12    0.58673    0.57155  2.59%  35.3  400s
 100997  3294     cutoff   30         0.58673    0.57201  2.51%  35.3  405s
H101117  3294                       0.5867328    0.57204  2.50%  35.3  407s
 101626  3096     cutoff   32         0.58673    0.57236  2.45%  35.2  411s
 103040  2787    0.57579   29   12    0.58673    0.57301  2.34%  35.2  415s
 105638  2320     cutoff   25         0.58673    0.57431  2.12%  35.2  421s
H107096  2068                       0.5867328    0.57514  1.98%  35.2  424s
 107296  1935     cutoff   29         0.58673    0.57588  1.85%  35.2  425s
 108799  1514    0.58431   30   14    0.58673    0.57727  1.61%  35.1  431s
 110791   440     cutoff   30         0.58673    0.58050  1.06%  35.0  435s

Cutting planes:
  Implied bound: 275
  MIR: 149
  Flow cover: 510
  RLT: 76
  Relax-and-lift: 115

Explored 111699 nodes (3899534 simplex iterations) in 436.13 seconds (560.63 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.586733 0.586733 0.599781 ... 0.693623

Optimal solution found (tolerance 1.00e-04)
Best objective 5.867328068242e-01, best bound 5.866833040459e-01, gap 0.0084%
The objective bound is 0.586683 while the objective upper bound is 0.586733

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

        Job resource usage summary 

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

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