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 1.44423 seconds.
    local lower bound updated to 0.249368
    local upper bound updated to 0.452306

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.559978
    local lower bound updated to 0.521213

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 17.952 seconds.
    local lower bound updated to 0.337382
    local upper bound updated to 0.468977

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 4.05763 seconds.
    local lower bound updated to 0.453827
    local upper bound updated to 0.465457

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 2.51313 seconds.
    local lower bound updated to 0.455202
    local upper bound updated to 0.460684

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.5838 seconds.
    local lower bound updated to 0.455202
    local upper bound updated to 0.455271

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.491083
    local lower bound updated to 0.455271

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.476574
    local lower bound updated to 0.468545

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.476574
    local lower bound updated to 0.469219

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.474402
    local lower bound updated to 0.47009

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.473787
    local lower bound updated to 0.472629

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 92.1695 seconds.
    local lower bound updated to 0.405695
    local upper bound updated to 0.472365

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 13.7449 seconds.
    local lower bound updated to 0.429066
    local upper bound updated to 0.470504

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 42.6321 seconds.
    local lower bound updated to 0.446777
    local upper bound updated to 0.46878

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 50.1599 seconds.
    local lower bound updated to 0.460006
    local upper bound updated to 0.467494

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 51.0959 seconds.
    local lower bound updated to 0.465487
    local upper bound updated to 0.467252

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 84.1895 seconds.
    local lower bound updated to 0.466647
    local upper bound updated to 0.467232

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 70.8097 seconds.
    local lower bound updated to 0.466892
    local upper bound updated to 0.467193

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 72.8301 seconds.
    local lower bound updated to 0.466892
    local upper bound updated to 0.467164

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 66.8114 seconds.
    local lower bound updated to 0.467164
    local upper bound updated to 0.467164
The Primal step takes 544.502 (S)
lower bound updated to 0.467164

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.505165
    local lower bound updated to 0.467164

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.470998
    local lower bound updated to 0.467217

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.470998
    local lower bound updated to 0.467217

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 81.8385 seconds.
    local lower bound updated to 0.414325
    local upper bound updated to 0.467217

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 78.9881 seconds.
    local lower bound updated to 0.467066
    local upper bound updated to 0.46721

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 88.2441 seconds.
    local lower bound updated to 0.46707
    local upper bound updated to 0.467197

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 89.8401 seconds.
    local lower bound updated to 0.467168
    local upper bound updated to 0.467196

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.499067
    local lower bound updated to 0.467196

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 82.2614 seconds.
    local lower bound updated to 0.414325
    local upper bound updated to 0.467196

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 75.4966 seconds.
    local lower bound updated to 0.467196
    local upper bound updated to 0.467196
The time obtain the optimal randomized assortment is 1157.85 (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_200.000000_207318.log"
The computation time limit for a lower bound is 84840
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 7325 nonzeros
Model fingerprint: 0xb8c1128a
Variable types: 1629 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        [2e-01, 1e+00]
Presolve removed 494 rows and 896 columns
Presolve time: 0.03s
Presolved: 1722 rows, 798 columns, 6610 nonzeros
Variable types: 736 continuous, 62 integer (62 binary)
Found heuristic solution: objective 0.5946681

Root relaxation: objective 3.638940e-01, 224 iterations, 0.01 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.36389    0   23    0.59467    0.36389  38.8%     -    0s
H    0     0                       0.5326846    0.36389  31.7%     -    0s
H    0     0                       0.5239349    0.36389  30.5%     -    0s
     0     0    0.36584    0   27    0.52393    0.36584  30.2%     -    0s
     0     0    0.36584    0   27    0.52393    0.36584  30.2%     -    0s
H    0     0                       0.5238694    0.39153  25.3%     -    0s
     0     0    0.39176    0   26    0.52387    0.39176  25.2%     -    0s
H    0     0                       0.4799387    0.39176  18.4%     -    0s
H    0     0                       0.4691255    0.39188  16.5%     -    0s
     0     0    0.39235    0   26    0.46913    0.39235  16.4%     -    0s
H    0     0                       0.4686968    0.40445  13.7%     -    0s
     0     0    0.40445    0   28    0.46870    0.40445  13.7%     -    0s
     0     0    0.40513    0   27    0.46870    0.40513  13.6%     -    0s
     0     0    0.40897    0   28    0.46870    0.40897  12.7%     -    0s
H    0     0                       0.4684372    0.40897  12.7%     -    0s
     0     0    0.40997    0   30    0.46844    0.40997  12.5%     -    0s
     0     0    0.41124    0   28    0.46844    0.41124  12.2%     -    0s
H    0     0                       0.4677342    0.41124  12.1%     -    0s
H    0     0                       0.4671958    0.41124  12.0%     -    2s
     0     2    0.41124    0   28    0.46720    0.41124  12.0%     -    2s
  1588   553    0.45560   14   16    0.46720    0.43173  7.59%  21.1    5s
  2494   758     cutoff   15         0.46720    0.43457  6.98%  20.6   10s
  3228   911    0.43735    8   29    0.46720    0.43588  6.70%  20.0   16s
  3791  1020    0.45778   24   16    0.46720    0.43588  6.70%  21.9   21s
  5280   925    0.45673   22   16    0.46720    0.44113  5.58%  22.2   25s
  5590   879    0.45549   27   20    0.46720    0.44201  5.39%  22.2   30s
  7048   960    0.46023   26   12    0.46720    0.44482  4.79%  22.0   35s
  8842  1125 infeasible   26         0.46720    0.44741  4.23%  21.5   41s
  9325  1188    0.46309   27    8    0.46720    0.44789  4.13%  21.4   45s
 11423  1279    0.45843   30    8    0.46720    0.44999  3.68%  21.2   50s
 12891  1294    0.46715   21   16    0.46720    0.45150  3.36%  21.1   55s
 14178  1265    0.46345   25   10    0.46720    0.45256  3.13%  20.9   60s
 16591  1150    0.46124   26   14    0.46720    0.45440  2.74%  20.5   66s
 18203   887    0.46179   30   14    0.46720    0.45569  2.46%  20.3   71s
 20075   450 infeasible   29         0.46720    0.45820  1.92%  20.1   75s

Cutting planes:
  Gomory: 1
  Implied bound: 170
  MIR: 103
  Flow cover: 321
  RLT: 57
  Relax-and-lift: 53

Explored 21666 nodes (432539 simplex iterations) in 78.50 seconds (45.25 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.467196 0.467734 0.468437 ... 0.594668

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

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

        Job resource usage summary 

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

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