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.02551 seconds.
    local lower bound updated to 0.305872
    local upper bound updated to 0.558435

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.631555
    local lower bound updated to 0.610047

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 61.3803 seconds.
    local lower bound updated to 0.426172
    local upper bound updated to 0.578254

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 24.6276 seconds.
    local lower bound updated to 0.550639
    local upper bound updated to 0.573295

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 15.3415 seconds.
    local lower bound updated to 0.550639
    local upper bound updated to 0.57236

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 27.6691 seconds.
    local lower bound updated to 0.561663
    local upper bound updated to 0.570437

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 27.6432 seconds.
    local lower bound updated to 0.568881
    local upper bound updated to 0.569484

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.594542
    local lower bound updated to 0.578476

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.594542
    local lower bound updated to 0.584311

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.594542
    local lower bound updated to 0.584731

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.586833
    local lower bound updated to 0.584858

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.585536
    local lower bound updated to 0.585068

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.585068
    local lower bound updated to 0.585068
The Dual step takes 3.23245 (S)
upper bound updated to 0.585068
*** OUTER ITERATION 2 ***

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 34.5456 seconds.
    local lower bound updated to 0.563508
    local upper bound updated to 0.583295

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.572572
    local upper bound updated to 0.581711

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.572735
    local upper bound updated to 0.580848

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.574985
    local upper bound updated to 0.57851

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.574985
    local upper bound updated to 0.577917

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.576592
    local upper bound updated to 0.577848

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.576874
    local upper bound updated to 0.57784

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.576874
    local upper bound updated to 0.577297

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.576874
    local upper bound updated to 0.577258

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.577082
    local upper bound updated to 0.577251

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.577145
    local upper bound updated to 0.577247

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.018 seconds.
    local lower bound updated to 0.577247
    local upper bound updated to 0.577247
The Primal step takes 1234.84 (S)
lower bound updated to 0.577247

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.586657
    local lower bound updated to 0.577247

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.579881
    local lower bound updated to 0.577321

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.57785
    local lower bound updated to 0.577388

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.5148
    local upper bound updated to 0.577315

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.57665
    local upper bound updated to 0.577307

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.028 seconds.
    local lower bound updated to 0.576965
    local upper bound updated to 0.577288

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.577192
    local upper bound updated to 0.577288

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.586072
    local lower bound updated to 0.577288

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.5148
    local upper bound updated to 0.577288

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.577288
    local upper bound updated to 0.577288
The time obtain the optimal randomized assortment is 2166.76 (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_100.000000_101018.log"
The computation time limit for a lower bound is 83810
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 2216 rows, 1694 columns and 7351 nonzeros
Model fingerprint: 0x1a39e39d
Variable types: 1629 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [3e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [5e-01, 1e+00]
Presolve removed 528 rows and 914 columns
Presolve time: 0.03s
Presolved: 1688 rows, 780 columns, 6671 nonzeros
Variable types: 718 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.6968918
Found heuristic solution: objective 0.6542887

Root relaxation: objective 3.733300e-01, 184 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.37333    0   24    0.65429    0.37333  42.9%     -    0s
H    0     0                       0.6382750    0.37333  41.5%     -    0s
     0     0    0.37686    0   29    0.63828    0.37686  41.0%     -    0s
H    0     0                       0.6335523    0.37686  40.5%     -    0s
     0     0    0.45099    0   30    0.63355    0.45099  28.8%     -    0s
H    0     0                       0.6162116    0.45099  26.8%     -    0s
     0     0    0.45141    0   28    0.61621    0.45141  26.7%     -    0s
H    0     0                       0.5823079    0.46989  19.3%     -    0s
     0     0    0.46989    0   28    0.58231    0.46989  19.3%     -    0s
     0     0    0.47033    0   28    0.58231    0.47033  19.2%     -    0s
     0     0    0.47793    0   26    0.58231    0.47793  17.9%     -    0s
     0     0    0.47815    0   27    0.58231    0.47815  17.9%     -    0s
     0     0    0.48065    0   29    0.58231    0.48065  17.5%     -    0s
H    0     0                       0.5781116    0.48065  16.9%     -    2s
     0     2    0.48065    0   29    0.57811    0.48065  16.9%     -    2s
H  504   298                       0.5772943    0.50249  13.0%  24.3    4s
   596   330     cutoff   13         0.57729    0.50693  12.2%  23.4    5s
H  600   330                       0.5772877    0.50693  12.2%  23.3    5s
  2133  1002    0.54486   10   29    0.57729    0.51706  10.4%  21.6   10s
  2500  1079    0.53640   20   16    0.57729    0.51706  10.4%  23.4   15s
  3687  1104    0.54007   20   21    0.57729    0.52136  9.69%  24.2   21s
  4069  1134    0.56077   26   14    0.57729    0.52355  9.31%  24.3   27s
  4495  1139    0.55895   22   16    0.57729    0.52554  8.96%  24.4   30s
  5503  1229     cutoff   28         0.57729    0.52891  8.38%  24.3   35s
  6408  1465 infeasible   27         0.57729    0.53026  8.15%  24.4   40s
  7853  1762    0.56326   24   10    0.57729    0.53265  7.73%  24.4   46s
  8904  2086    0.55622   23   15    0.57729    0.53442  7.43%  24.3   50s
H 9974  2243                       0.5772877    0.53571  7.20%  24.3   53s
 10423  2411    0.54226   21   17    0.57729    0.53620  7.12%  24.3   56s
H10801  2441                       0.5772877    0.53648  7.07%  24.4   58s
 10906  2610    0.56457   22   14    0.57729    0.53656  7.06%  24.4   60s
 12875  2939    0.56229   24   14    0.57729    0.53872  6.68%  24.3   65s
H13001  2939                       0.5772876    0.53872  6.68%  24.3   65s
H13207  2939                       0.5772876    0.53875  6.68%  24.3   65s
 14455  3231     cutoff   34         0.57729    0.54021  6.42%  24.3   71s
 16979  3463    0.55437   24   14    0.57729    0.54200  6.11%  24.1   75s
 18571  3737    0.57048   23   12    0.57729    0.54309  5.92%  24.1   80s
 19729  3872    0.56151   27    8    0.57729    0.54372  5.82%  24.0   85s
 20759  4021    0.56610   22   12    0.57729    0.54432  5.71%  24.0   90s
 22229  4178     cutoff   29         0.57729    0.54506  5.58%  24.0   95s
 23430  4313    0.57071   25   14    0.57729    0.54590  5.44%  23.9  101s
 25547  4521    0.55434   23   16    0.57729    0.54701  5.24%  23.9  105s
 27559  4695     cutoff   26         0.57729    0.54800  5.07%  23.8  110s
 29309  4797     cutoff   22         0.57729    0.54856  4.98%  23.7  116s
H30487  4866                       0.5772876    0.54892  4.91%  23.7  118s
 30719  4897     cutoff   29         0.57729    0.54902  4.90%  23.6  120s
 32287  4952     cutoff   25         0.57729    0.54976  4.77%  23.6  125s
H32291  4952                       0.5772876    0.54976  4.77%  23.6  125s
 33937  5075     cutoff   25         0.57729    0.55029  4.68%  23.5  130s
 35308  5151    0.56558   27    8    0.57729    0.55079  4.59%  23.5  135s
 37740  5224    0.56386   23   15    0.57729    0.55171  4.43%  23.4  140s
 39910  5256     cutoff   27         0.57729    0.55252  4.29%  23.4  146s
 41334  5264     cutoff   27         0.57729    0.55304  4.20%  23.3  150s
 42881  5260    0.57711   28   10    0.57729    0.55346  4.13%  23.3  155s
 44476  5241    0.57215   28    8    0.57729    0.55403  4.03%  23.3  160s
 46259  5230    0.56029   23   14    0.57729    0.55469  3.92%  23.2  165s
 48019  5198     cutoff   21         0.57729    0.55521  3.82%  23.2  170s
 50414  5130     cutoff   29         0.57729    0.55602  3.68%  23.1  175s
 52088  5053    0.56977   27   12    0.57729    0.55648  3.60%  23.1  180s
 54044  4926     cutoff   32         0.57729    0.55718  3.48%  23.1  185s
 56096  4811 infeasible   24         0.57729    0.55788  3.36%  23.1  190s
 57389  4682     cutoff   25         0.57729    0.55831  3.29%  23.0  195s
 58571  4545     cutoff   29         0.57729    0.55879  3.20%  23.0  200s
 62142  4159    0.56879   26   10    0.57729    0.56022  2.96%  22.9  205s
 63700  3918 infeasible   26         0.57729    0.56079  2.86%  22.9  210s
 65878  3500    0.57222   27   10    0.57729    0.56180  2.68%  22.9  215s
 67760  3188    0.57245   29   12    0.57729    0.56272  2.52%  22.9  220s
 70364  2620    0.57239   30    4    0.57729    0.56380  2.34%  22.8  225s
 72869  2077     cutoff   24         0.57729    0.56585  1.98%  22.8  231s
 76022   774     cutoff   31         0.57729    0.56906  1.42%  22.7  236s

Cutting planes:
  Implied bound: 205
  MIR: 124
  Flow cover: 349
  RLT: 56
  Relax-and-lift: 73

Explored 77587 nodes (1755016 simplex iterations) in 237.30 seconds (224.13 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.577288 0.577288 0.577288 ... 0.638275

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

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

        Job resource usage summary 

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

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