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 0.213952 seconds.
    local lower bound updated to 0.245848
    local upper bound updated to 0.473001

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.62809
    local lower bound updated to 0.599775

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 3.24609 seconds.
    local lower bound updated to 0.37235
    local upper bound updated to 0.511339

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.21308 seconds.
    local lower bound updated to 0.490747
    local upper bound updated to 0.510483

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 14.4533 seconds.
    local lower bound updated to 0.501929
    local upper bound updated to 0.509777

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 15.5505 seconds.
    local lower bound updated to 0.501929
    local upper bound updated to 0.507252

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 19.8617 seconds.
    local lower bound updated to 0.503766
    local upper bound updated to 0.506291

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 17.5689 seconds.
    local lower bound updated to 0.503966
    local upper bound updated to 0.505203

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.533352
    local lower bound updated to 0.505203

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.527826
    local lower bound updated to 0.510556

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.526932
    local lower bound updated to 0.512193

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 21.8127 seconds.
    local lower bound updated to 0.417542
    local upper bound updated to 0.512193

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 11.0419 seconds.
    local lower bound updated to 0.492565
    local upper bound updated to 0.510652

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 27.7647 seconds.
    local lower bound updated to 0.505845
    local upper bound updated to 0.50966

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 32.6123 seconds.
    local lower bound updated to 0.507237
    local upper bound updated to 0.509533

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 34.3603 seconds.
    local lower bound updated to 0.507237
    local upper bound updated to 0.508623

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 37.1443 seconds.
    local lower bound updated to 0.507237
    local upper bound updated to 0.508159

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 34.5393 seconds.
    local lower bound updated to 0.507642
    local upper bound updated to 0.508101

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 30.1184 seconds.
    local lower bound updated to 0.507642
    local upper bound updated to 0.508085

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 37.6672 seconds.
    local lower bound updated to 0.50775
    local upper bound updated to 0.507958

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 40.3253 seconds.
    local lower bound updated to 0.507834
    local upper bound updated to 0.507944

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.530994
    local lower bound updated to 0.509143

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.511933
    local lower bound updated to 0.509289

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 13.1384 seconds.
    local lower bound updated to 0.43004
    local upper bound updated to 0.509289

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0129 seconds.
    local lower bound updated to 0.505166
    local upper bound updated to 0.508822

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0178 seconds.
    local lower bound updated to 0.505679
    local upper bound updated to 0.508634

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 44.6128 seconds.
    local lower bound updated to 0.506241
    local upper bound updated to 0.508029

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0207 seconds.
    local lower bound updated to 0.50672
    local upper bound updated to 0.508028

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0188 seconds.
    local lower bound updated to 0.506833
    local upper bound updated to 0.507999

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0283 seconds.
    local lower bound updated to 0.507712
    local upper bound updated to 0.507982

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0211 seconds.
    local lower bound updated to 0.507855
    local upper bound updated to 0.507978

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0193 seconds.
    local lower bound updated to 0.507868
    local upper bound updated to 0.507973

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.528804
    local lower bound updated to 0.507973

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 14.6 seconds.
    local lower bound updated to 0.43004
    local upper bound updated to 0.507973

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0142 seconds.
    local lower bound updated to 0.507973
    local upper bound updated to 0.507973
The time obtain the optimal randomized assortment is 1058.83 (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_65018.log"
The computation time limit for a lower bound is 84937
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 1634 rows, 1250 columns and 5389 nonzeros
Model fingerprint: 0x6e2c697e
Variable types: 1185 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        [4e-01, 1e+00]
Presolve removed 376 rows and 668 columns
Presolve time: 0.01s
Presolved: 1258 rows, 582 columns, 4921 nonzeros
Variable types: 520 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.6756338
Found heuristic solution: objective 0.5534524

Root relaxation: objective 3.959910e-01, 151 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.39599    0   20    0.55345    0.39599  28.5%     -    0s
     0     0    0.39862    0   26    0.55345    0.39862  28.0%     -    0s
H    0     0                       0.5407238    0.43257  20.0%     -    0s
     0     0    0.43257    0   31    0.54072    0.43257  20.0%     -    0s
     0     0    0.43264    0   30    0.54072    0.43264  20.0%     -    0s
H    0     0                       0.5262430    0.44153  16.1%     -    0s
     0     0    0.44153    0   31    0.52624    0.44153  16.1%     -    0s
H    0     0                       0.5251577    0.44153  15.9%     -    0s
     0     0    0.44236    0   31    0.52516    0.44236  15.8%     -    0s
H    0     0                       0.5231431    0.44236  15.4%     -    0s
     0     0    0.44336    0   31    0.52314    0.44336  15.3%     -    0s
     0     0    0.44357    0   31    0.52314    0.44357  15.2%     -    0s
H    0     0                       0.5210162    0.44357  14.9%     -    0s
H    0     0                       0.5206414    0.44357  14.8%     -    0s
     0     0    0.44419    0   26    0.52064    0.44419  14.7%     -    0s
H    0     0                       0.5145699    0.44419  13.7%     -    0s
H    0     0                       0.5085065    0.44419  12.6%     -    1s
     0     2    0.44419    0   26    0.50851    0.44419  12.6%     -    1s
H  137   109                       0.5083311    0.44855  11.8%  26.2    1s
H  212   154                       0.5081031    0.45115  11.2%  25.1    1s
H  300   196                       0.5079734    0.45341  10.7%  23.3    2s
H  320   214                       0.5079734    0.45341  10.7%  23.3    2s
H  390   232                       0.5079734    0.45595  10.2%  22.6    3s
   893   408 infeasible   25         0.50797    0.46007  9.43%  22.9    5s
H 1326   544                       0.5079734    0.46291  8.87%  22.2    8s
  1758   706    0.48097    9   15    0.50797    0.46653  8.16%  21.8   10s
  3363  1065    0.49071   25    8    0.50797    0.47081  7.32%  21.1   15s
H 3586  1026                       0.5079734    0.47081  7.32%  21.2   16s
  5366   997    0.48998   21   22    0.50797    0.47209  7.06%  21.7   20s
  6852  1102    0.50439   25   10    0.50797    0.47695  6.11%  21.8   25s
  7586  1192    0.49505   26   18    0.50797    0.47816  5.87%  21.7   30s
  8812  1378     cutoff   30         0.50797    0.48076  5.36%  21.5   35s
H 9172  1378                       0.5079734    0.48126  5.26%  21.4   35s
H 9201  1389                       0.5079734    0.48133  5.25%  21.4   35s
 10665  1451     cutoff   27         0.50797    0.48384  4.75%  21.4   40s
 12406  1449     cutoff   28         0.50797    0.48670  4.19%  21.2   45s
 14920  1260    0.49650   26   12    0.50797    0.49027  3.49%  20.8   50s
 17544   794    0.50524   23   16    0.50797    0.49364  2.82%  20.4   55s

Cutting planes:
  Implied bound: 154
  MIR: 101
  Flow cover: 247
  RLT: 37
  Relax-and-lift: 87

Explored 20094 nodes (401452 simplex iterations) in 58.50 seconds (43.33 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.507973 0.507973 0.508331 ... 0.526243

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

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

        Job resource usage summary 

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

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