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 11.0384 seconds.
    local lower bound updated to 0.169525
    local upper bound updated to 0.328652

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.382073
    local lower bound updated to 0.359446

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.372304
    local lower bound updated to 0.371402

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.372304
    local lower bound updated to 0.372102

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.372102
    local lower bound updated to 0.372102
The Dual step takes 0.402735 (S)
upper bound updated to 0.372102
*** OUTER ITERATION 1 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 2.25867 seconds.
    local lower bound updated to 0.217309
    local upper bound updated to 0.326176

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 31.0669 seconds.
    local lower bound updated to 0.323741
    local upper bound updated to 0.32551

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 28.486 seconds.
    local lower bound updated to 0.323741
    local upper bound updated to 0.324813

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 29.4158 seconds.
    local lower bound updated to 0.324701
    local upper bound updated to 0.324791

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 39.1869 seconds.
    local lower bound updated to 0.324701
    local upper bound updated to 0.324769

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 48.7454 seconds.
    local lower bound updated to 0.324701
    local upper bound updated to 0.324747

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.348094
    local lower bound updated to 0.333391

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.348094
    local lower bound updated to 0.334055

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.344593
    local lower bound updated to 0.335731

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.344593
    local lower bound updated to 0.339496

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.344593
    local lower bound updated to 0.340275

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.341013
    local lower bound updated to 0.340298

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 65.4209 seconds.
    local lower bound updated to 0.274291
    local upper bound updated to 0.336482

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 63.776 seconds.
    local lower bound updated to 0.31832
    local upper bound updated to 0.336301

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 51.7157 seconds.
    local lower bound updated to 0.31832
    local upper bound updated to 0.335021

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.024 seconds.
    local lower bound updated to 0.326968
    local upper bound updated to 0.3306

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.326968
    local upper bound updated to 0.329756

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.326968
    local upper bound updated to 0.329584

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.328705
    local upper bound updated to 0.32941

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.032 seconds.
    local lower bound updated to 0.328705
    local upper bound updated to 0.329351

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.328705
    local upper bound updated to 0.329297

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.026 seconds.
    local lower bound updated to 0.329249
    local upper bound updated to 0.329296

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.342565
    local lower bound updated to 0.333771

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

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

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.026 seconds.
    local lower bound updated to 0.326696
    local upper bound updated to 0.333579

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.331684
    local upper bound updated to 0.333534

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.331684
    local upper bound updated to 0.333461

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

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.024 seconds.
    local lower bound updated to 0.331684
    local upper bound updated to 0.33278

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.332201
    local upper bound updated to 0.332779

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.332459
    local upper bound updated to 0.332764

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 100.022 seconds.
    local lower bound updated to 0.332567
    local upper bound updated to 0.332763

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.332719
    local upper bound updated to 0.332762

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.034 seconds.
    local lower bound updated to 0.332719
    local upper bound updated to 0.332759

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.034 seconds.
    local lower bound updated to 0.332728
    local upper bound updated to 0.332757

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.028 seconds.
    local lower bound updated to 0.332728
    local upper bound updated to 0.332757

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 100.02 seconds.
    local lower bound updated to 0.332746
    local upper bound updated to 0.332754
The Primal step takes 1400.45 (S)
lower bound updated to 0.332746

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.340611
    local lower bound updated to 0.332754

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.019 seconds.
    local lower bound updated to 0.278847
    local upper bound updated to 0.332754

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.332754
    local upper bound updated to 0.332754
The time obtain the optimal randomized assortment is 2828.5 (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_104518.log"
The computation time limit for a lower bound is 83149
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 2410 rows, 1842 columns and 7866 nonzeros
Model fingerprint: 0x0c168c80
Variable types: 1777 continuous, 65 integer (65 binary)
Coefficient statistics:
  Matrix range     [4e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [2e-01, 1e+00]
Presolve removed 554 rows and 1002 columns
Presolve time: 0.03s
Presolved: 1856 rows, 840 columns, 7172 nonzeros
Variable types: 777 continuous, 63 integer (63 binary)
Found heuristic solution: objective 0.4391587
Found heuristic solution: objective 0.3853505

Root relaxation: objective 1.945248e-01, 187 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.19452    0   24    0.38535    0.19452  49.5%     -    0s
H    0     0                       0.3492986    0.19452  44.3%     -    0s
     0     0    0.19645    0   26    0.34930    0.19645  43.8%     -    0s
     0     0    0.19645    0   26    0.34930    0.19645  43.8%     -    0s
H    0     0                       0.3464395    0.23720  31.5%     -    0s
     0     0    0.23720    0   31    0.34644    0.23720  31.5%     -    0s
     0     0    0.24250    0   33    0.34644    0.24250  30.0%     -    0s
     0     0    0.24250    0   33    0.34644    0.24250  30.0%     -    0s
H    0     0                       0.3441156    0.24250  29.5%     -    0s
H    0     0                       0.3440584    0.24250  29.5%     -    0s
     0     0    0.25089    0   30    0.34406    0.25089  27.1%     -    0s
     0     0    0.25187    0   31    0.34406    0.25187  26.8%     -    0s
     0     0    0.25411    0   31    0.34406    0.25411  26.1%     -    0s
H    0     0                       0.3350205    0.25411  24.2%     -    0s
H    0     0                       0.3338898    0.25411  23.9%     -    0s
H    0     0                       0.3334233    0.25411  23.8%     -    2s
     0     2    0.25411    0   31    0.33342    0.25411  23.8%     -    2s
H    9    12                       0.3332885    0.26010  22.0%  78.4    2s
H   60    64                       0.3332283    0.26037  21.9%  48.5    2s
H   62    64                       0.3327823    0.26037  21.8%  47.4    2s
H  200   177                       0.3327542    0.26305  20.9%  32.9    3s
   792   445    0.28960   11   16    0.33275    0.27422  17.6%  25.4    5s
  1864   864     cutoff   18         0.33275    0.27908  16.1%  21.5   10s
  2116   919    0.27929   12   30    0.33275    0.27929  16.1%  22.9   15s
H 2302   942                       0.3327542    0.27929  16.1%  24.6   17s
  3190  1057    0.28532   19   21    0.33275    0.28340  14.8%  25.5   21s
  4078  1106    0.30478   20   18    0.33275    0.28682  13.8%  25.6   25s
  4995  1217     cutoff   27         0.33275    0.28892  13.2%  25.5   30s
H 5165  1263                       0.3327542    0.28900  13.1%  25.5   31s
  5280  1347    0.30803   23   14    0.33275    0.28933  13.1%  25.5   35s
H 5389  1366                       0.3327542    0.28949  13.0%  25.6   35s
  6323  1699     cutoff   24         0.33275    0.29093  12.6%  25.5   40s
  7031  1930    0.30765   18   16    0.33275    0.29172  12.3%  25.5   45s
  8420  2322    0.33107   21   16    0.33275    0.29325  11.9%  25.2   51s
  9655  2611    0.32677   28   19    0.33275    0.29417  11.6%  25.2   55s
 10670  2959 infeasible   25         0.33275    0.29518  11.3%  25.0   60s
 12171  3248    0.30205   22   20    0.33275    0.29660  10.9%  24.9   66s
 13927  3592    0.30541   20   18    0.33275    0.29773  10.5%  24.9   71s
 15793  3838    0.30168   19   18    0.33275    0.29865  10.2%  24.8   75s
 17763  4200    0.32406   26   12    0.33275    0.29999  9.85%  24.6   80s
 18912  4461    0.32404   28   18    0.33275    0.30064  9.65%  24.4   85s
 20441  4711     cutoff   26         0.33275    0.30121  9.48%  24.3   91s
 21458  4868    0.31966   25   12    0.33275    0.30153  9.38%  24.2   95s
 23138  5078    0.31417   22   17    0.33275    0.30217  9.19%  24.1  101s
 24783  5261    0.31502   21   21    0.33275    0.30280  9.00%  24.1  105s
 27026  5534    0.31943   21   16    0.33275    0.30365  8.74%  23.9  110s
 29161  5731    0.33195   26   12    0.33275    0.30436  8.53%  23.9  115s
 31560  5956    0.30610   25   14    0.33275    0.30517  8.29%  23.7  121s
 33015  6113    0.32587   29   14    0.33275    0.30552  8.18%  23.6  125s
 35070  6214    0.31708   24   16    0.33275    0.30620  7.98%  23.5  130s
 36840  6321    0.32740   24   15    0.33275    0.30667  7.84%  23.4  135s
 38622  6414     cutoff   30         0.33275    0.30723  7.67%  23.4  141s
 40252  6481    0.31222   25   12    0.33275    0.30766  7.54%  23.4  146s
 41621  6561    0.31928   25   14    0.33275    0.30803  7.43%  23.3  150s
 43962  6655     cutoff   27         0.33275    0.30859  7.26%  23.2  155s
 45374  6696    0.32153   28   18    0.33275    0.30899  7.14%  23.2  160s
 47002  6729     cutoff   28         0.33275    0.30939  7.02%  23.1  166s
 48774  6731     cutoff   28         0.33275    0.30972  6.92%  23.0  170s
 51086  6757    0.32989   30   10    0.33275    0.31029  6.75%  23.0  176s
 51768  6784     cutoff   25         0.33275    0.31046  6.70%  22.9  180s
 52911  6812 infeasible   26         0.33275    0.31062  6.65%  22.9  185s
 56166  6811     cutoff   28         0.33275    0.31133  6.44%  22.8  190s
 57980  6827     cutoff   29         0.33275    0.31176  6.31%  22.8  196s
 59807  6784     cutoff   30         0.33275    0.31220  6.18%  22.7  200s
 62331  6667    0.32988   28   12    0.33275    0.31287  5.98%  22.6  205s
 64123  6635    0.32338   23   14    0.33275    0.31329  5.85%  22.5  210s
 66056  6560    0.31701   25   16    0.33275    0.31376  5.71%  22.5  215s
 67918  6475    0.31930   26   12    0.33275    0.31418  5.58%  22.4  220s
 71125  6288    0.32937   29   14    0.33275    0.31493  5.36%  22.3  226s
 73150  6135     cutoff   25         0.33275    0.31542  5.21%  22.2  230s
 74929  5985 infeasible   21         0.33275    0.31595  5.05%  22.1  235s
 76975  5853    0.32894   27   13    0.33275    0.31634  4.93%  22.1  240s
 78979  5671     cutoff   25         0.33275    0.31689  4.77%  22.0  245s
 80815  5480     cutoff   26         0.33275    0.31736  4.63%  21.9  251s
 82657  5374    0.32175   20   20    0.33275    0.31766  4.54%  21.9  255s
 84943  5068    0.32370   28   12    0.33275    0.31835  4.33%  21.8  261s
 86767  4894    0.32856   26   10    0.33275    0.31883  4.19%  21.7  265s
 89374  4571 infeasible   30         0.33275    0.31957  3.96%  21.6  270s
 91860  4205    0.32639   24   12    0.33275    0.32028  3.75%  21.5  275s
 94090  3878    0.32188   26   12    0.33275    0.32088  3.57%  21.4  280s
 96066  3594    0.32320   26   12    0.33275    0.32154  3.37%  21.3  286s
 96542  3426     cutoff   32         0.33275    0.32174  3.31%  21.3  290s
 99086  2971    0.32826   32   18    0.33275    0.32282  2.99%  21.2  295s
H99800  2728                       0.3327542    0.32310  2.90%  21.2  297s
 101798  2269 infeasible   28         0.33275    0.32393  2.65%  21.1  300s
 105116  1023    0.33085   28    8    0.33275    0.32641  1.91%  20.9  305s

Cutting planes:
  Implied bound: 218
  MIR: 134
  Flow cover: 405
  RLT: 59
  Relax-and-lift: 64

Explored 107727 nodes (2237818 simplex iterations) in 306.55 seconds (268.42 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.332754 0.332782 0.333228 ... 0.34644

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

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

        Job resource usage summary 

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

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