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.257738 seconds.
    local lower bound updated to 0.305363
    local upper bound updated to 0.606349

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.697508
    local lower bound updated to 0.679727

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.685365
    local lower bound updated to 0.683977

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 0.829758 seconds.
    local lower bound updated to 0.410023
    local upper bound updated to 0.620341

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.60917 seconds.
    local lower bound updated to 0.57142
    local upper bound updated to 0.614664

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 2.02513 seconds.
    local lower bound updated to 0.594785
    local upper bound updated to 0.598852

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.05109 seconds.
    local lower bound updated to 0.594785
    local upper bound updated to 0.597267

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 0.378941 seconds.
    local lower bound updated to 0.596689
    local upper bound updated to 0.597141

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 0.555979 seconds.
    local lower bound updated to 0.596795
    local upper bound updated to 0.596882

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.646742
    local lower bound updated to 0.600652

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.63392
    local lower bound updated to 0.627462

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.63392
    local lower bound updated to 0.629034

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.63392
    local lower bound updated to 0.629412

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.69936 seconds.
    local lower bound updated to 0.494361
    local upper bound updated to 0.625354

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.91292 seconds.
    local lower bound updated to 0.596871
    local upper bound updated to 0.613269

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 2.39896 seconds.
    local lower bound updated to 0.605698
    local upper bound updated to 0.612702

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 2.68714 seconds.
    local lower bound updated to 0.605698
    local upper bound updated to 0.612023

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 3.1837 seconds.
    local lower bound updated to 0.607856
    local upper bound updated to 0.610387

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 3.74015 seconds.
    local lower bound updated to 0.610387
    local upper bound updated to 0.610387
The Primal step takes 15.6434 (S)
lower bound updated to 0.610387

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.632436
    local lower bound updated to 0.610407

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.632436
    local lower bound updated to 0.617245

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.632436
    local lower bound updated to 0.620626

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.632436
    local lower bound updated to 0.621191

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.632436
    local lower bound updated to 0.623618

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.627971
    local lower bound updated to 0.623716

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 12.4014 seconds.
    local lower bound updated to 0.54556
    local upper bound updated to 0.622845

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.77034 seconds.
    local lower bound updated to 0.605714
    local upper bound updated to 0.620862

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 16.6859 seconds.
    local lower bound updated to 0.615754
    local upper bound updated to 0.620634

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 15.8943 seconds.
    local lower bound updated to 0.616518
    local upper bound updated to 0.619452

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 14.4008 seconds.
    local lower bound updated to 0.616639
    local upper bound updated to 0.61939

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 10.7569 seconds.
    local lower bound updated to 0.61792
    local upper bound updated to 0.619344

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 22.9892 seconds.
    local lower bound updated to 0.618007
    local upper bound updated to 0.619245

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 22.7317 seconds.
    local lower bound updated to 0.618236
    local upper bound updated to 0.619181

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 23.6194 seconds.
    local lower bound updated to 0.618236
    local upper bound updated to 0.619137

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 15.6811 seconds.
    local lower bound updated to 0.618656
    local upper bound updated to 0.619109

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 15.959 seconds.
    local lower bound updated to 0.618855
    local upper bound updated to 0.619109

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 20.7165 seconds.
    local lower bound updated to 0.618864
    local upper bound updated to 0.619106

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 20.5434 seconds.
    local lower bound updated to 0.619003
    local upper bound updated to 0.619104

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 20.2724 seconds.
    local lower bound updated to 0.619003
    local upper bound updated to 0.619097

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 23.6713 seconds.
    local lower bound updated to 0.619003
    local upper bound updated to 0.619094

    ++ RESTRICTED PRIMAL ITERATION 16 ++
One worst-case parameter optimization takes 15.6499 seconds.
    local lower bound updated to 0.619038
    local upper bound updated to 0.619092

    ++ RESTRICTED PRIMAL ITERATION 17 ++
One worst-case parameter optimization takes 18.8072 seconds.
    local lower bound updated to 0.619092
    local upper bound updated to 0.619092
The Primal step takes 293.678 (S)
lower bound updated to 0.619092

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.629938
    local lower bound updated to 0.619092

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.62949
    local lower bound updated to 0.619095

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.619272
    local lower bound updated to 0.619096

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 21.0163 seconds.
    local lower bound updated to 0.549613
    local upper bound updated to 0.619096

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 23.5632 seconds.
    local lower bound updated to 0.618976
    local upper bound updated to 0.619093

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 25.6814 seconds.
    local lower bound updated to 0.619093
    local upper bound updated to 0.619093
The time obtain the optimal randomized assortment is 398.519 (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_15_4_200.000000_209816.log"
The computation time limit for a lower bound is 85599
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 2640 rows, 2138 columns and 8406 nonzeros
Model fingerprint: 0xb7c809b1
Variable types: 2081 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [3e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [6e-01, 1e+00]
Presolve removed 766 rows and 1328 columns
Presolve time: 0.04s
Presolved: 1874 rows, 810 columns, 7173 nonzeros
Variable types: 755 continuous, 55 integer (55 binary)
Found heuristic solution: objective 0.6869101
Found heuristic solution: objective 0.6803505
Found heuristic solution: objective 0.6698487

Root relaxation: objective 5.293848e-01, 227 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.52938    0   16    0.66985    0.52938  21.0%     -    0s
H    0     0                       0.6502515    0.52938  18.6%     -    0s
H    0     0                       0.6443682    0.52938  17.8%     -    0s
H    0     0                       0.6358080    0.53750  15.5%     -    0s
H    0     0                       0.6281576    0.53750  14.4%     -    0s
     0     0    0.53750    0   20    0.62816    0.53750  14.4%     -    0s
     0     0    0.55946    0   24    0.62816    0.55946  10.9%     -    0s
H    0     0                       0.6218146    0.55946  10.0%     -    0s
     0     0    0.55954    0   23    0.62181    0.55954  10.0%     -    0s
H    0     0                       0.6194136    0.56608  8.61%     -    0s
     0     0    0.56608    0   25    0.61941    0.56608  8.61%     -    0s
     0     0    0.56632    0   25    0.61941    0.56632  8.57%     -    0s
     0     0    0.56923    0   24    0.61941    0.56923  8.10%     -    0s
H    0     0                       0.6193661    0.56966  8.03%     -    0s
     0     0    0.56966    0   24    0.61937    0.56966  8.03%     -    0s
H    0     0                       0.6191704    0.56966  8.00%     -    0s
     0     0    0.57082    0   23    0.61917    0.57082  7.81%     -    0s
H    0     0                       0.6190931    0.57082  7.80%     -    2s
     0     2    0.57082    0   23    0.61909    0.57082  7.80%     -    2s
   497   179     cutoff   13         0.61909    0.58531  5.46%  28.0    5s
  2274   369    0.61144   12   12    0.61909    0.59752  3.49%  24.7   10s
  3247   386    0.61714   13    8    0.61909    0.60199  2.76%  23.9   15s
  4212   331    0.61653   10   12    0.61909    0.60546  2.20%  23.3   20s
  5350    79    0.61524   15    6    0.61909    0.61122  1.27%  22.5   25s

Cutting planes:
  Gomory: 1
  Implied bound: 299
  MIR: 83
  Flow cover: 227
  RLT: 26
  Relax-and-lift: 165

Explored 5859 nodes (129876 simplex iterations) in 25.37 seconds (13.20 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.619093 0.61917 0.619366 ... 0.669849

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

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

        Job resource usage summary 

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

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