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 2.72199 seconds.
    local lower bound updated to 0.275763
    local upper bound updated to 0.542665

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.599541
    local lower bound updated to 0.589552

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 19.9119 seconds.
    local lower bound updated to 0.376761
    local upper bound updated to 0.559519

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.305929 seconds.
    local lower bound updated to 0.442348
    local upper bound updated to 0.549135

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 11.2915 seconds.
    local lower bound updated to 0.528701
    local upper bound updated to 0.540531

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 9.4232 seconds.
    local lower bound updated to 0.535753
    local upper bound updated to 0.537387

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 8.70896 seconds.
    local lower bound updated to 0.535753
    local upper bound updated to 0.536473

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 7.69005 seconds.
    local lower bound updated to 0.536434
    local upper bound updated to 0.536457

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.591311
    local lower bound updated to 0.568765

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 41.5283 seconds.
    local lower bound updated to 0.423085
    local upper bound updated to 0.56116

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.870576 seconds.
    local lower bound updated to 0.476932
    local upper bound updated to 0.556217

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 20.0632 seconds.
    local lower bound updated to 0.536715
    local upper bound updated to 0.551162

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 18.4536 seconds.
    local lower bound updated to 0.539162
    local upper bound updated to 0.543172

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 21.618 seconds.
    local lower bound updated to 0.539162
    local upper bound updated to 0.542439

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 17.0083 seconds.
    local lower bound updated to 0.53967
    local upper bound updated to 0.541464

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 20.6175 seconds.
    local lower bound updated to 0.53967
    local upper bound updated to 0.540434

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.586858
    local lower bound updated to 0.55431

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0117 seconds.
    local lower bound updated to 0.450155
    local upper bound updated to 0.55431

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 4.30981 seconds.
    local lower bound updated to 0.506038
    local upper bound updated to 0.549561

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 9.46651 seconds.
    local lower bound updated to 0.534637
    local upper bound updated to 0.545108

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 23.4965 seconds.
    local lower bound updated to 0.540994
    local upper bound updated to 0.544797

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 28.1774 seconds.
    local lower bound updated to 0.541922
    local upper bound updated to 0.543874

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 27.3729 seconds.
    local lower bound updated to 0.541922
    local upper bound updated to 0.543494

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 33.5456 seconds.
    local lower bound updated to 0.542276
    local upper bound updated to 0.543248

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 28.423 seconds.
    local lower bound updated to 0.542276
    local upper bound updated to 0.543123

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 33.9743 seconds.
    local lower bound updated to 0.542276
    local upper bound updated to 0.543101

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.568045
    local lower bound updated to 0.545544

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.552701
    local lower bound updated to 0.54679

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0184 seconds.
    local lower bound updated to 0.475009
    local upper bound updated to 0.545313

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0137 seconds.
    local lower bound updated to 0.542473
    local upper bound updated to 0.5452

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0212 seconds.
    local lower bound updated to 0.542473
    local upper bound updated to 0.545082

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0202 seconds.
    local lower bound updated to 0.542938
    local upper bound updated to 0.544951

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0127 seconds.
    local lower bound updated to 0.543949
    local upper bound updated to 0.544943

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.016 seconds.
    local lower bound updated to 0.543953
    local upper bound updated to 0.544915

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0171 seconds.
    local lower bound updated to 0.54459
    local upper bound updated to 0.544835

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0132 seconds.
    local lower bound updated to 0.54459
    local upper bound updated to 0.544651

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0228 seconds.
    local lower bound updated to 0.544631
    local upper bound updated to 0.544651

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.559225
    local lower bound updated to 0.544651

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 60.0151 seconds.
    local lower bound updated to 0.475009
    local upper bound updated to 0.544651

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0135 seconds.
    local lower bound updated to 0.544651
    local upper bound updated to 0.544651
The time obtain the optimal randomized assortment is 1249.97 (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_61718.log"
The computation time limit for a lower bound is 84743
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 1440 rows, 1102 columns and 4724 nonzeros
Model fingerprint: 0x08323009
Variable types: 1037 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [2e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 326 rows and 576 columns
Presolve time: 0.01s
Presolved: 1114 rows, 526 columns, 4324 nonzeros
Variable types: 463 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.6506218
Found heuristic solution: objective 0.6267829

Root relaxation: objective 3.812214e-01, 161 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.38122    0   19    0.62678    0.38122  39.2%     -    0s
H    0     0                       0.6210206    0.38122  38.6%     -    0s
H    0     0                       0.6078459    0.38345  36.9%     -    0s
     0     0    0.38345    0   33    0.60785    0.38345  36.9%     -    0s
H    0     0                       0.5779348    0.38345  33.7%     -    0s
     0     0    0.38345    0   33    0.57793    0.38345  33.7%     -    0s
     0     0    0.41739    0   30    0.57793    0.41739  27.8%     -    0s
H    0     0                       0.5576856    0.41739  25.2%     -    0s
     0     0    0.41785    0   30    0.55769    0.41785  25.1%     -    0s
     0     0    0.43941    0   30    0.55769    0.43941  21.2%     -    0s
H    0     0                       0.5548179    0.43941  20.8%     -    0s
H    0     0                       0.5482379    0.44020  19.7%     -    0s
H    0     0                       0.5475551    0.44020  19.6%     -    0s
     0     0    0.44020    0   31    0.54756    0.44020  19.6%     -    0s
     0     0    0.44716    0   33    0.54756    0.44716  18.3%     -    0s
     0     0    0.44823    0   31    0.54756    0.44823  18.1%     -    0s
     0     0    0.44920    0   32    0.54756    0.44920  18.0%     -    0s
H    0     0                       0.5471360    0.44920  17.9%     -    1s
     0     2    0.44920    0   32    0.54714    0.44920  17.9%     -    1s
H  412   246                       0.5446507    0.46399  14.8%  17.5    2s
  1331   680    0.50797    8   23    0.54465    0.47387  13.0%  15.4    5s
  2440  1111    0.49192   26   23    0.54465    0.47889  12.1%  16.2   10s
  3092  1236    0.50889   23   22    0.54465    0.48249  11.4%  17.1   15s
  3890  1281    0.50864   18   22    0.54465    0.48692  10.6%  17.5   20s
  6769  1734    0.53014   29   16    0.54465    0.49802  8.56%  18.2   25s
  9000  2218     cutoff   27         0.54465    0.50181  7.87%  18.1   30s
H 9002  2218                       0.5446507    0.50181  7.87%  18.1   30s
 10991  2595    0.52686   28   15    0.54465    0.50488  7.30%  18.3   35s
H12187  2773                       0.5446507    0.50671  6.97%  18.3   38s
 12685  2892    0.53155   24   22    0.54465    0.50706  6.90%  18.3   41s
 14298  3069    0.52274   26   18    0.54465    0.50934  6.48%  18.4   45s
H15900  3215                       0.5446507    0.51070  6.23%  18.3   46s
 17487  3321     cutoff   30         0.54465    0.51237  5.93%  18.3   50s
 20179  3432     cutoff   29         0.54465    0.51491  5.46%  18.2   56s
H20322  3432                       0.5446507    0.51494  5.46%  18.2   56s
 22126  3501     cutoff   27         0.54465    0.51620  5.22%  18.2   60s
 25238  3472    0.53170   27   12    0.54465    0.51861  4.78%  18.2   65s
 27688  3375    0.53008   26   18    0.54465    0.52038  4.46%  18.2   70s
H28078  3359                       0.5446507    0.52059  4.42%  18.2   72s
 29232  3245    0.54454   30   12    0.54465    0.52161  4.23%  18.2   75s
 31165  3093     cutoff   26         0.54465    0.52280  4.01%  18.2   80s
H32081  3050                       0.5446506    0.52317  3.94%  18.1   81s
H32269  3050                       0.5446506    0.52317  3.94%  18.1   81s
 34120  2728    0.54117   25   16    0.54465    0.52537  3.54%  18.1   85s
H35209  2648                       0.5446506    0.52592  3.44%  18.1   86s
 36922  2277    0.53751   27   14    0.54465    0.52763  3.12%  18.1   90s
 40155  1629     cutoff   32         0.54465    0.52998  2.69%  18.0   95s
 44672   523    0.54155   27   14    0.54465    0.53528  1.72%  17.7  100s

Cutting planes:
  Implied bound: 135
  MIR: 119
  Flow cover: 207
  RLT: 43
  Relax-and-lift: 68

Explored 46357 nodes (807483 simplex iterations) in 101.19 seconds (88.22 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.544651 0.544651 0.544651 ... 0.607846

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

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

        Job resource usage summary 

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

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