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.364677 seconds.
    local lower bound updated to 0.251897
    local upper bound updated to 0.486631

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.607711
    local lower bound updated to 0.607645

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 1.30059 seconds.
    local lower bound updated to 0.361626
    local upper bound updated to 0.547619

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.239522 seconds.
    local lower bound updated to 0.454301
    local upper bound updated to 0.537853

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.90609 seconds.
    local lower bound updated to 0.511045
    local upper bound updated to 0.532202

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 2.95674 seconds.
    local lower bound updated to 0.526409
    local upper bound updated to 0.52693

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 2.07821 seconds.
    local lower bound updated to 0.526409
    local upper bound updated to 0.52649

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.564426
    local lower bound updated to 0.542095

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.560046
    local lower bound updated to 0.542742

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.560046
    local lower bound updated to 0.543283

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.558137
    local lower bound updated to 0.555796

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.558137
    local lower bound updated to 0.555796

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.558137
    local lower bound updated to 0.555937

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 10.3826 seconds.
    local lower bound updated to 0.450895
    local upper bound updated to 0.547317

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.29199 seconds.
    local lower bound updated to 0.50775
    local upper bound updated to 0.538657

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 10.3047 seconds.
    local lower bound updated to 0.533141
    local upper bound updated to 0.538297

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 14.2754 seconds.
    local lower bound updated to 0.533584
    local upper bound updated to 0.538121

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 12.8291 seconds.
    local lower bound updated to 0.53505
    local upper bound updated to 0.537657

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 13.6703 seconds.
    local lower bound updated to 0.537225
    local upper bound updated to 0.537619

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 11.0293 seconds.
    local lower bound updated to 0.537225
    local upper bound updated to 0.537596

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 13.4843 seconds.
    local lower bound updated to 0.537582
    local upper bound updated to 0.537595

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.56595
    local lower bound updated to 0.537595

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.540642
    local lower bound updated to 0.537665

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.539309
    local lower bound updated to 0.537745

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 22.0029 seconds.
    local lower bound updated to 0.463057
    local upper bound updated to 0.537745

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 16.3821 seconds.
    local lower bound updated to 0.536669
    local upper bound updated to 0.53771

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 17.5098 seconds.
    local lower bound updated to 0.537094
    local upper bound updated to 0.537679

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 20.7891 seconds.
    local lower bound updated to 0.537141
    local upper bound updated to 0.537661

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 17.1705 seconds.
    local lower bound updated to 0.537618
    local upper bound updated to 0.537659

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 20.3059 seconds.
    local lower bound updated to 0.537626
    local upper bound updated to 0.537659

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.5625
    local lower bound updated to 0.537659

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 19.9966 seconds.
    local lower bound updated to 0.463057
    local upper bound updated to 0.537659

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 18.4281 seconds.
    local lower bound updated to 0.537659
    local upper bound updated to 0.537659
The time obtain the optimal randomized assortment is 288.059 (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_60.000000_60316.log"
The computation time limit for a lower bound is 85709
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 20 physical cores, 40 logical processors, using up to 4 threads
Optimize a model with 2000 rows, 1618 columns and 6426 nonzeros
Model fingerprint: 0x7fc6f24e
Variable types: 1561 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [5e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [4e-01, 1e+00]
Presolve removed 570 rows and 978 columns
Presolve time: 0.02s
Presolved: 1430 rows, 640 columns, 5599 nonzeros
Variable types: 584 continuous, 56 integer (56 binary)
Found heuristic solution: objective 0.6894602
Found heuristic solution: objective 0.6302130

Root relaxation: objective 3.385411e-01, 139 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.33854    0   25    0.63021    0.33854  46.3%     -    0s
H    0     0                       0.6247473    0.33854  45.8%     -    0s
H    0     0                       0.6220996    0.33854  45.6%     -    0s
     0     0    0.34505    0   28    0.62210    0.34505  44.5%     -    0s
H    0     0                       0.6211266    0.34505  44.4%     -    0s
H    0     0                       0.5843516    0.34505  41.0%     -    0s
     0     0    0.34505    0   28    0.58435    0.34505  41.0%     -    0s
H    0     0                       0.5836911    0.38934  33.3%     -    0s
     0     0    0.38934    0   28    0.58369    0.38934  33.3%     -    0s
H    0     0                       0.5394235    0.38934  27.8%     -    0s
     0     0    0.38979    0   28    0.53942    0.38979  27.7%     -    0s
     0     0    0.41355    0   27    0.53942    0.41355  23.3%     -    0s
     0     0    0.41741    0   27    0.53942    0.41741  22.6%     -    0s
     0     0    0.42267    0   26    0.53942    0.42267  21.6%     -    0s
H    0     0                       0.5378177    0.42267  21.4%     -    0s
     0     0    0.42290    0   28    0.53782    0.42290  21.4%     -    0s
     0     0    0.42345    0   27    0.53782    0.42345  21.3%     -    0s
H    0     0                       0.5376589    0.42345  21.2%     -    1s
     0     2    0.42345    0   27    0.53766    0.42345  21.2%     -    1s
  1375   391    0.52026   12   14    0.53766    0.47706  11.3%  24.7    5s
  3344   519    0.52762   13    8    0.53766    0.49619  7.71%  21.3   11s
  5264   414     cutoff   14         0.53766    0.51152  4.86%  19.6   15s

Cutting planes:
  Implied bound: 202
  MIR: 68
  Flow cover: 242
  RLT: 21
  Relax-and-lift: 79

Explored 7231 nodes (130700 simplex iterations) in 18.11 seconds (12.85 work units)
Thread count was 4 (of 40 available processors)

Solution count 10: 0.537659 0.537818 0.539424 ... 0.68946

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

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

        Job resource usage summary 

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

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