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 5.47146 seconds.
    local lower bound updated to 0.248878
    local upper bound updated to 0.47415

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.490229
    local lower bound updated to 0.44484

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 47.4218 seconds.
    local lower bound updated to 0.350433
    local upper bound updated to 0.445144

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.37886 seconds.
    local lower bound updated to 0.424209
    local upper bound updated to 0.438332

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 7.49548 seconds.
    local lower bound updated to 0.436866
    local upper bound updated to 0.437347

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 5.24159 seconds.
    local lower bound updated to 0.437347
    local upper bound updated to 0.437347
The Primal step takes 62.5473 (S)
lower bound updated to 0.437347

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.462401
    local lower bound updated to 0.448806

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.460676
    local lower bound updated to 0.450365

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.45703
    local lower bound updated to 0.453128

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 23.648 seconds.
    local lower bound updated to 0.427183
    local upper bound updated to 0.448954

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 14.6909 seconds.
    local lower bound updated to 0.434333
    local upper bound updated to 0.44697

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 30.2384 seconds.
    local lower bound updated to 0.44169
    local upper bound updated to 0.445369

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.452278
    local lower bound updated to 0.445661

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.449264
    local lower bound updated to 0.446909

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 41.1394 seconds.
    local lower bound updated to 0.432794
    local upper bound updated to 0.446659

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.017 seconds.
    local lower bound updated to 0.444687
    local upper bound updated to 0.446554

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

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.024 seconds.
    local lower bound updated to 0.446487
    local upper bound updated to 0.446547

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.461657
    local lower bound updated to 0.446547

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.032 seconds.
    local lower bound updated to 0.405575
    local upper bound updated to 0.446547

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.446547
    local upper bound updated to 0.446547
The time obtain the optimal randomized assortment is 1009.27 (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_101618.log"
The computation time limit for a lower bound is 84981
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 1828 rows, 1398 columns and 6013 nonzeros
Model fingerprint: 0x55cdf8f3
Variable types: 1333 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-06, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 442 rows and 770 columns
Presolve time: 0.02s
Presolved: 1386 rows, 628 columns, 5449 nonzeros
Variable types: 566 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.6093594
Found heuristic solution: objective 0.5290725

Root relaxation: objective 2.738243e-01, 177 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.27382    0   14    0.52907    0.27382  48.2%     -    0s
H    0     0                       0.4653394    0.27618  40.7%     -    0s
     0     0    0.27618    0   14    0.46534    0.27618  40.7%     -    0s
     0     0    0.32659    0   24    0.46534    0.32659  29.8%     -    0s
H    0     0                       0.4560309    0.32659  28.4%     -    0s
H    0     0                       0.4522544    0.32659  27.8%     -    0s
     0     0    0.32684    0   24    0.45225    0.32684  27.7%     -    0s
     0     0    0.33109    0   30    0.45225    0.33109  26.8%     -    0s
     0     0    0.33363    0   29    0.45225    0.33363  26.2%     -    0s
H    0     0                       0.4486812    0.33363  25.6%     -    0s
     0     0    0.34369    0   27    0.44868    0.34369  23.4%     -    0s
     0     0    0.34464    0   27    0.44868    0.34464  23.2%     -    0s
H    0     0                       0.4481810    0.34464  23.1%     -    0s
     0     0    0.34556    0   28    0.44818    0.34556  22.9%     -    0s
H    0     0                       0.4465468    0.34556  22.6%     -    1s
     0     2    0.34556    0   28    0.44655    0.34556  22.6%     -    1s
H  674   323                       0.4465468    0.36975  17.2%  18.8    2s
H 1058   455                       0.4465468    0.37323  16.4%  18.4    3s
H 1922   808                       0.4465468    0.37891  15.1%  17.6    3s
  2091   883    0.41011   14   30    0.44655    0.38025  14.8%  17.4    6s
H 2415   920                       0.4465468    0.38025  14.8%  18.8    7s
  3492   877     cutoff   20         0.44655    0.38679  13.4%  20.1   10s
H 4154   789                       0.4465468    0.39075  12.5%  20.1   10s
H 4221   780                       0.4465468    0.39157  12.3%  20.2   11s
H 4409   764                       0.4465468    0.39212  12.2%  20.2   12s
  4875   895    0.40699   18   19    0.44655    0.39402  11.8%  20.3   15s
H 5100   930                       0.4465468    0.39449  11.7%  20.4   17s
H 5169   930                       0.4465468    0.39449  11.7%  20.4   17s
  6127  1301     cutoff   26         0.44655    0.39812  10.8%  20.6   20s
  7715  1700    0.42797   23   17    0.44655    0.40101  10.2%  20.6   25s
H 8001  1700                       0.4465468    0.40105  10.2%  20.6   25s
  9237  1918    0.42030   21   14    0.44655    0.40293  9.77%  20.4   30s
H 9611  1918                       0.4465468    0.40342  9.66%  20.4   30s
H 9789  2002                       0.4465468    0.40352  9.64%  20.4   30s
H11419  2225                       0.4465468    0.40498  9.31%  20.2   33s
H11450  2225                       0.4465468    0.40498  9.31%  20.2   33s
 11599  2315    0.43746   26   10    0.44655    0.40554  9.18%  20.2   35s
H12187  2315                       0.4465468    0.40601  9.08%  20.2   35s
H12519  2435                       0.4465468    0.40625  9.02%  20.2   37s
H13387  2478                       0.4465468    0.40682  8.90%  20.2   37s
H14036  2534                       0.4465468    0.40770  8.70%  20.2   39s
 14037  2669     cutoff   24         0.44655    0.40772  8.70%  20.2   40s
H15171  2716                       0.4465468    0.40869  8.48%  20.2   41s
H16480  2869                       0.4465468    0.40966  8.26%  20.1   44s
 17410  3027     cutoff   24         0.44655    0.41071  8.02%  20.1   45s
 19898  3193    0.43014   25   11    0.44655    0.41239  7.65%  20.0   50s
 22297  3286    0.43235   24   10    0.44655    0.41380  7.33%  19.9   55s
H22409  3286                       0.4465468    0.41380  7.33%  19.9   55s
H23795  3375                       0.4465468    0.41463  7.15%  19.8   59s
 23975  3378    0.43240   25   12    0.44655    0.41470  7.13%  19.8   60s
H24720  3423                       0.4465468    0.41511  7.04%  19.8   62s
H25000  3451                       0.4465468    0.41519  7.02%  19.8   64s
 25391  3461    0.43578   20   15    0.44655    0.41550  6.95%  19.8   65s
H26773  3483                       0.4465468    0.41617  6.80%  19.8   69s
H26989  3483                       0.4465468    0.41644  6.74%  19.8   69s
 26994  3503    0.44341   24   13    0.44655    0.41648  6.73%  19.8   70s
H27100  3503                       0.4465468    0.41648  6.73%  19.8   70s
 29158  3529     cutoff   26         0.44655    0.41760  6.48%  19.8   75s
 31179  3517    0.42666   22   14    0.44655    0.41869  6.24%  19.7   80s
H33014  3506                       0.4465468    0.41965  6.02%  19.7   82s
 34535  3476    0.44111   28   10    0.44655    0.42050  5.83%  19.7   86s
 36449  3427    0.44057   21   10    0.44655    0.42141  5.63%  19.6   91s
 38789  3277    0.43141   23   14    0.44655    0.42271  5.34%  19.6   96s
 41504  3053    0.43830   24   10    0.44655    0.42445  4.95%  19.6  100s
H41600  3053                       0.4465468    0.42445  4.95%  19.6  100s
 43780  2819    0.43898   21   12    0.44655    0.42566  4.68%  19.6  105s
 46595  2439    0.43425   22   19    0.44655    0.42769  4.22%  19.5  110s
H46800  2439                       0.4465468    0.42769  4.22%  19.5  110s
H47900  2266                       0.4465468    0.42834  4.08%  19.5  112s
 49614  1876    0.43168   18   18    0.44655    0.42990  3.73%  19.5  115s
 53589   933    0.43988   23   15    0.44655    0.43562  2.45%  19.6  120s

Cutting planes:
  Implied bound: 171
  MIR: 99
  Flow cover: 273
  RLT: 49
  Relax-and-lift: 71

Explored 55213 nodes (1074563 simplex iterations) in 122.31 seconds (109.20 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.446547 0.446547 0.446547 ... 0.529072

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

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

        Job resource usage summary 

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

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