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.09597 seconds.
    local lower bound updated to 0.209882
    local upper bound updated to 0.3768

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.551073
    local lower bound updated to 0.536829

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 5.37858 seconds.
    local lower bound updated to 0.28785
    local upper bound updated to 0.450976

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 16.8336 seconds.
    local lower bound updated to 0.439641
    local upper bound updated to 0.44173

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 15.4406 seconds.
    local lower bound updated to 0.439641
    local upper bound updated to 0.440587

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 14.9242 seconds.
    local lower bound updated to 0.439927
    local upper bound updated to 0.440526

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 19.8639 seconds.
    local lower bound updated to 0.439927
    local upper bound updated to 0.440432

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 22.3472 seconds.
    local lower bound updated to 0.440043
    local upper bound updated to 0.440307

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.501394
    local lower bound updated to 0.457971

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.480619
    local lower bound updated to 0.461457

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.472819
    local lower bound updated to 0.471364

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0138 seconds.
    local lower bound updated to 0.357898
    local upper bound updated to 0.470005

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.886394 seconds.
    local lower bound updated to 0.357898
    local upper bound updated to 0.457255

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 30.1054 seconds.
    local lower bound updated to 0.424111
    local upper bound updated to 0.456069

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0123 seconds.
    local lower bound updated to 0.443076
    local upper bound updated to 0.454499

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0176 seconds.
    local lower bound updated to 0.445932
    local upper bound updated to 0.452765

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.012 seconds.
    local lower bound updated to 0.449782
    local upper bound updated to 0.451681

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0115 seconds.
    local lower bound updated to 0.451636
    local upper bound updated to 0.45167

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.494737
    local lower bound updated to 0.45537

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.459943
    local lower bound updated to 0.457031

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.458775
    local lower bound updated to 0.457353

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.458775
    local lower bound updated to 0.45745

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0147 seconds.
    local lower bound updated to 0.392134
    local upper bound updated to 0.45745

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0155 seconds.
    local lower bound updated to 0.447952
    local upper bound updated to 0.457392

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0164 seconds.
    local lower bound updated to 0.452083
    local upper bound updated to 0.45733

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0275 seconds.
    local lower bound updated to 0.454937
    local upper bound updated to 0.457295

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0188 seconds.
    local lower bound updated to 0.456339
    local upper bound updated to 0.45703

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0237 seconds.
    local lower bound updated to 0.456339
    local upper bound updated to 0.45703

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0211 seconds.
    local lower bound updated to 0.456339
    local upper bound updated to 0.456885

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0197 seconds.
    local lower bound updated to 0.456339
    local upper bound updated to 0.456847

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.017 seconds.
    local lower bound updated to 0.456421
    local upper bound updated to 0.456836

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0151 seconds.
    local lower bound updated to 0.456771
    local upper bound updated to 0.456833

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0168 seconds.
    local lower bound updated to 0.456833
    local upper bound updated to 0.456833
The Primal step takes 660.257 (S)
lower bound updated to 0.456833

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.481526
    local lower bound updated to 0.456833

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.458884
    local lower bound updated to 0.456833

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.457167
    local lower bound updated to 0.456896

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0144 seconds.
    local lower bound updated to 0.454224
    local upper bound updated to 0.456883

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0187 seconds.
    local lower bound updated to 0.455582
    local upper bound updated to 0.456857

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0156 seconds.
    local lower bound updated to 0.456639
    local upper bound updated to 0.456848

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0186 seconds.
    local lower bound updated to 0.456755
    local upper bound updated to 0.45684

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.479533
    local lower bound updated to 0.45684

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0183 seconds.
    local lower bound updated to 0.400778
    local upper bound updated to 0.45684

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0173 seconds.
    local lower bound updated to 0.45684
    local upper bound updated to 0.45684
The time obtain the optimal randomized assortment is 1667.03 (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_66118.log"
The computation time limit for a lower bound is 84319
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 2604 rows, 1990 columns and 8643 nonzeros
Model fingerprint: 0x8cfc8a82
Variable types: 1925 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        [1e-01, 1e+00]
Presolve removed 569 rows and 1073 columns
Presolve time: 0.02s
Presolved: 2035 rows, 917 columns, 7959 nonzeros
Variable types: 853 continuous, 64 integer (64 binary)
Found heuristic solution: objective 0.5727642
Found heuristic solution: objective 0.5635989

Root relaxation: objective 2.004795e-01, 278 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.20048    0   15    0.56360    0.20048  64.4%     -    0s
H    0     0                       0.4751441    0.20048  57.8%     -    0s
H    0     0                       0.4738168    0.20177  57.4%     -    0s
     0     0    0.20177    0   18    0.47382    0.20177  57.4%     -    0s
     0     0    0.27323    0   28    0.47382    0.27323  42.3%     -    0s
     0     0    0.27323    0   28    0.47382    0.27323  42.3%     -    0s
     0     0    0.28552    0   28    0.47382    0.28552  39.7%     -    0s
     0     0    0.28669    0   28    0.47382    0.28669  39.5%     -    0s
H    0     0                       0.4736658    0.30088  36.5%     -    0s
     0     0    0.30088    0   29    0.47367    0.30088  36.5%     -    0s
H    0     0                       0.4699423    0.30088  36.0%     -    0s
H    0     0                       0.4671178    0.30382  35.0%     -    0s
     0     0    0.30382    0   30    0.46712    0.30382  35.0%     -    0s
     0     0    0.30899    0   30    0.46712    0.30899  33.9%     -    0s
H    0     0                       0.4568396    0.30899  32.4%     -    2s
     0     2    0.30899    0   30    0.45684    0.30899  32.4%     -    2s
   345   213     cutoff   14         0.45684    0.32708  28.4%  28.8    5s
  1196   688    0.40230   27   16    0.45684    0.34739  24.0%  29.0   10s
  1996   826    0.42122   26   16    0.45684    0.35841  21.5%  30.1   16s
  2691   884    0.37350   24   19    0.45684    0.36455  20.2%  30.1   20s
  3269  1022    0.41896   32   19    0.45684    0.36776  19.5%  29.7   26s
  3891  1204    0.42830   33   18    0.45684    0.37100  18.8%  29.3   31s
  4744  1467    0.44890   34   11    0.45684    0.37617  17.7%  28.9   35s
  5677  1679    0.44383   31    8    0.45684    0.37867  17.1%  28.5   40s
  6677  1946     cutoff   27         0.45684    0.38221  16.3%  28.2   45s
  7705  2204 infeasible   35         0.45684    0.38496  15.7%  27.9   50s
  9591  2500    0.40328   27   14    0.45684    0.38884  14.9%  27.6   55s
 10766  2733    0.44128   32   16    0.45684    0.39111  14.4%  27.4   60s
 12933  3073    0.45271   28   10    0.45684    0.39467  13.6%  26.9   65s
 14800  3338    0.44539   30   13    0.45684    0.39763  13.0%  26.8   71s
 15581  3396    0.41153   25   19    0.45684    0.39826  12.8%  26.7   75s
 17085  3620     cutoff   33         0.45684    0.39962  12.5%  26.4   80s
 18473  3808     cutoff   33         0.45684    0.40116  12.2%  26.3   86s
H20345  3960                       0.4568396    0.40269  11.9%  26.1   90s
 21440  4114    0.41524   27   16    0.45684    0.40391  11.6%  26.0   96s
 23194  4209    0.45166   29   10    0.45684    0.40509  11.3%  25.8  101s
 23888  4253    0.42210   27   19    0.45684    0.40544  11.3%  25.8  105s
H23917  4253                       0.4568396    0.40565  11.2%  25.8  105s
 25690  4419    0.42397   29   15    0.45684    0.40745  10.8%  25.7  110s
 27750  4543    0.45450   31   14    0.45684    0.40891  10.5%  25.5  116s
 28806  4614    0.44639   30   15    0.45684    0.40952  10.4%  25.4  120s
 30448  4746    0.45162   28   17    0.45684    0.41072  10.1%  25.3  125s
 32518  4826     cutoff   29         0.45684    0.41187  9.84%  25.1  130s
 34889  4915    0.44976   31   14    0.45684    0.41345  9.50%  24.9  135s
 36485  4994    0.42276   26   16    0.45684    0.41437  9.30%  24.9  141s
 38019  5053    0.43355   30   16    0.45684    0.41533  9.09%  24.8  146s
 38735  5076     cutoff   30         0.45684    0.41592  8.96%  24.8  150s
 41802  5087     cutoff   32         0.45684    0.41733  8.65%  24.6  155s
 43006  5086     cutoff   33         0.45684    0.41806  8.49%  24.6  162s
 44989  5110    0.43541   32   16    0.45684    0.41914  8.25%  24.5  165s
 46779  5108    0.44931   29   12    0.45684    0.42006  8.05%  24.4  171s
 48822  5084    0.44311   30   19    0.45684    0.42112  7.82%  24.3  175s
 50713  5084    0.45013   35   18    0.45684    0.42201  7.62%  24.2  181s
 52399  5043    0.43230   33   15    0.45684    0.42297  7.41%  24.2  185s
 53634  5002    0.43184   31   14    0.45684    0.42349  7.30%  24.1  190s
 55795  4922     cutoff   30         0.45684    0.42452  7.07%  24.0  196s
 57269  4842    0.44078   32   10    0.45684    0.42520  6.92%  24.0  200s
H57983  4816                       0.4568396    0.42557  6.84%  24.0  202s
 58940  4745     cutoff   32         0.45684    0.42614  6.72%  23.9  206s
 60198  4692     cutoff   31         0.45684    0.42674  6.59%  23.9  210s
 62499  4544    0.44442   29   12    0.45684    0.42770  6.38%  23.8  216s
H63014  4543                       0.4568395    0.42801  6.31%  23.7  217s
H63200  4462                       0.4568395    0.42803  6.31%  23.7  217s
 64521  4399    0.45077   29   18    0.45684    0.42872  6.16%  23.7  220s
 65802  4256    0.44341   31   11    0.45684    0.42947  5.99%  23.6  225s
 68313  3986 infeasible   31         0.45684    0.43058  5.75%  23.5  230s
 71617  3647 infeasible   39         0.45684    0.43255  5.32%  23.4  236s
 74093  3357     cutoff   28         0.45684    0.43378  5.05%  23.3  241s
 75379  3178    0.44479   30   15    0.45684    0.43464  4.86%  23.3  245s
 77402  2774     cutoff   33         0.45684    0.43596  4.57%  23.2  250s
 79681  2337     cutoff   34         0.45684    0.43800  4.12%  23.1  255s
 81680  1765    0.44946   30   15    0.45684    0.44048  3.58%  23.0  260s
 84051   987 infeasible   32         0.45684    0.44392  2.83%  22.9  265s

Cutting planes:
  Implied bound: 233
  MIR: 141
  Flow cover: 437
  RLT: 76
  Relax-and-lift: 68

Explored 85955 nodes (1958618 simplex iterations) in 266.62 seconds (288.67 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.456839 0.45684 0.45684 ... 0.572764

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

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

        Job resource usage summary 

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

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