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.29772 seconds.
    local lower bound updated to 0.192832
    local upper bound updated to 0.372278

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.39708
    local lower bound updated to 0.379123

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.39708
    local lower bound updated to 0.393901

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 2.91901 seconds.
    local lower bound updated to 0.265482
    local upper bound updated to 0.368655

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.47151 seconds.
    local lower bound updated to 0.355365
    local upper bound updated to 0.364151

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 7.34336 seconds.
    local lower bound updated to 0.355365
    local upper bound updated to 0.362219

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 14.2367 seconds.
    local lower bound updated to 0.359324
    local upper bound updated to 0.360736

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 12.0226 seconds.
    local lower bound updated to 0.359324
    local upper bound updated to 0.360575

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 12.4655 seconds.
    local lower bound updated to 0.360262
    local upper bound updated to 0.360472

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 15.3127 seconds.
    local lower bound updated to 0.36031
    local upper bound updated to 0.360393

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 14.7068 seconds.
    local lower bound updated to 0.360376
    local upper bound updated to 0.360392

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.379577
    local lower bound updated to 0.362709

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.379577
    local lower bound updated to 0.362709

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.373492
    local lower bound updated to 0.370058

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.373492
    local lower bound updated to 0.370296

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.373492
    local lower bound updated to 0.37089

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 26.1703 seconds.
    local lower bound updated to 0.307176
    local upper bound updated to 0.37089

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0229 seconds.
    local lower bound updated to 0.353264
    local upper bound updated to 0.36981

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0291 seconds.
    local lower bound updated to 0.362139
    local upper bound updated to 0.368656

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0228 seconds.
    local lower bound updated to 0.365714
    local upper bound updated to 0.367936

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0235 seconds.
    local lower bound updated to 0.366589
    local upper bound updated to 0.367857

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0222 seconds.
    local lower bound updated to 0.366589
    local upper bound updated to 0.3678

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.0196 seconds.
    local lower bound updated to 0.366711
    local upper bound updated to 0.367633

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 60.0196 seconds.
    local lower bound updated to 0.367258
    local upper bound updated to 0.367585

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 60.0169 seconds.
    local lower bound updated to 0.367258
    local upper bound updated to 0.367469

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 60.0208 seconds.
    local lower bound updated to 0.367258
    local upper bound updated to 0.367442

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 60.0167 seconds.
    local lower bound updated to 0.367418
    local upper bound updated to 0.367437

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 60.0166 seconds.
    local lower bound updated to 0.367425
    local upper bound updated to 0.367437

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.373141
    local lower bound updated to 0.367437

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.371713
    local lower bound updated to 0.367476

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 40.2258 seconds.
    local lower bound updated to 0.309209
    local upper bound updated to 0.367476

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0217 seconds.
    local lower bound updated to 0.367203
    local upper bound updated to 0.367468

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0267 seconds.
    local lower bound updated to 0.367468
    local upper bound updated to 0.367468
The Primal step takes 160.295 (S)
lower bound updated to 0.367468

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.372648
    local lower bound updated to 0.367468

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.368613
    local lower bound updated to 0.367468

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 23.3559 seconds.
    local lower bound updated to 0.307685
    local upper bound updated to 0.367468

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.027 seconds.
    local lower bound updated to 0.367468
    local upper bound updated to 0.367468
The time obtain the optimal randomized assortment is 1133.75 (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_65118.log"
The computation time limit for a lower bound is 84843
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 7353 nonzeros
Model fingerprint: 0x3ad9e6cc
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        [3e-01, 1e+00]
Presolve removed 498 rows and 904 columns
Presolve time: 0.03s
Presolved: 1718 rows, 790 columns, 6684 nonzeros
Variable types: 727 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.4135026

Root relaxation: objective 2.540474e-01, 220 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.25405    0   18    0.41350    0.25405  38.6%     -    0s
H    0     0                       0.3969374    0.25405  36.0%     -    0s
     0     0    0.25499    0   27    0.39694    0.25499  35.8%     -    0s
     0     0    0.29574    0   30    0.39694    0.29574  25.5%     -    0s
H    0     0                       0.3907029    0.29588  24.3%     -    0s
     0     0    0.29588    0   30    0.39070    0.29588  24.3%     -    0s
H    0     0                       0.3888850    0.29588  23.9%     -    0s
     0     0    0.30844    0   29    0.38888    0.30844  20.7%     -    0s
H    0     0                       0.3815580    0.30844  19.2%     -    0s
     0     0    0.30856    0   29    0.38156    0.30856  19.1%     -    0s
H    0     0                       0.3802429    0.30856  18.9%     -    0s
H    0     0                       0.3756298    0.31616  15.8%     -    0s
     0     0    0.31616    0   31    0.37563    0.31616  15.8%     -    0s
     0     0    0.31681    0   30    0.37563    0.31681  15.7%     -    0s
H    0     0                       0.3692399    0.31681  14.2%     -    0s
H    0     0                       0.3691886    0.31681  14.2%     -    0s
     0     0    0.31911    0   29    0.36919    0.31911  13.6%     -    0s
H    0     0                       0.3678531    0.31911  13.3%     -    2s
     0     2    0.31911    0   29    0.36785    0.31911  13.3%     -    2s
H  100    73                       0.3674678    0.31961  13.0%  28.1    4s
   300   154    0.35233   14   10    0.36747    0.32183  12.4%  23.9    6s
H  416   201                       0.3674678    0.32629  11.2%  24.0    7s
H  453   214                       0.3674678    0.32629  11.2%  23.9    7s
H  524   256                       0.3674678    0.32676  11.1%  23.9    9s
   688   338    0.33191   10   21    0.36747    0.32820  10.7%  23.4   10s
  1888   726    0.34538    8   12    0.36747    0.33285  9.42%  22.4   15s
H 2113   725                       0.3674678    0.33331  9.30%  21.8   20s
  3390   770    0.35154   21   17    0.36747    0.34378  6.45%  24.5   25s
H 3790   641                       0.3674678    0.34468  6.20%  24.3   26s
  4384   681    0.36339   19   17    0.36747    0.34617  5.80%  24.6   30s
  5380   883     cutoff   27         0.36747    0.34824  5.23%  24.6   35s
  6267  1029    0.36741   26    4    0.36747    0.34948  4.89%  24.6   40s
  7432  1135    0.36060   22   12    0.36747    0.35083  4.53%  24.7   46s
  8357  1172     cutoff   24         0.36747    0.35190  4.24%  24.9   50s
  8956  1186    0.36495   23   12    0.36747    0.35232  4.12%  24.9   55s
H 9154  1186                       0.3674678    0.35237  4.11%  24.9   55s
H10243  1203                       0.3674678    0.35372  3.74%  25.1   59s
 10244  1187     cutoff   27         0.36747    0.35374  3.74%  25.1   61s
H11626  1167                       0.3674678    0.35472  3.47%  24.9   64s
 11627  1149    0.36648   22   17    0.36747    0.35474  3.46%  24.9   66s
 13306  1050    0.36014   25   10    0.36747    0.35637  3.02%  24.7   70s
 14781   896    0.36659   25   10    0.36747    0.35748  2.72%  24.5   75s
 16918   540    0.36512   20   19    0.36747    0.36013  2.00%  24.3   81s
 18368    45    0.36650   24   12    0.36747    0.36385  0.98%  24.0   85s

Cutting planes:
  Implied bound: 215
  MIR: 140
  Flow cover: 339
  RLT: 45
  Relax-and-lift: 66

Explored 18771 nodes (450159 simplex iterations) in 86.52 seconds (51.50 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.367468 0.367468 0.367853 ... 0.390703

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

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

        Job resource usage summary 

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

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