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.14954 seconds.
    local lower bound updated to 0.432017
    local upper bound updated to 0.704897

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.761362
    local lower bound updated to 0.755518

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 0.557768 seconds.
    local lower bound updated to 0.527226
    local upper bound updated to 0.718726

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.08169 seconds.
    local lower bound updated to 0.716734
    local upper bound updated to 0.717867

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.03549 seconds.
    local lower bound updated to 0.71688
    local upper bound updated to 0.717069

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 1.14592 seconds.
    local lower bound updated to 0.717069
    local upper bound updated to 0.717069
The Primal step takes 3.82884 (S)
lower bound updated to 0.717069

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.735088
    local lower bound updated to 0.720839

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.722002
    local lower bound updated to 0.720888

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.720946
    local lower bound updated to 0.72091

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.72091
    local lower bound updated to 0.72091
The Dual step takes 0.36461 (S)
upper bound updated to 0.72091
*** OUTER ITERATION 2 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 4.7063 seconds.
    local lower bound updated to 0.613318
    local upper bound updated to 0.720853

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 1.50731 seconds.
    local lower bound updated to 0.670518
    local upper bound updated to 0.720652

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 17.174 seconds.
    local lower bound updated to 0.712072
    local upper bound updated to 0.720509

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 11.3592 seconds.
    local lower bound updated to 0.716818
    local upper bound updated to 0.720237

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 6.04817 seconds.
    local lower bound updated to 0.717301
    local upper bound updated to 0.720093

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 8.53673 seconds.
    local lower bound updated to 0.719551
    local upper bound updated to 0.72001

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 5.28676 seconds.
    local lower bound updated to 0.719551
    local upper bound updated to 0.719974

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 5.9314 seconds.
    local lower bound updated to 0.719624
    local upper bound updated to 0.719809

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 6.19776 seconds.
    local lower bound updated to 0.719652
    local upper bound updated to 0.719801

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 6.52 seconds.
    local lower bound updated to 0.719801
    local upper bound updated to 0.719801
The Primal step takes 73.3034 (S)
lower bound updated to 0.719801

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.742115
    local lower bound updated to 0.719801

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.723401
    local lower bound updated to 0.720627

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 3.60825 seconds.
    local lower bound updated to 0.636142
    local upper bound updated to 0.720627

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.743201
    local lower bound updated to 0.720627

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 3.90422 seconds.
    local lower bound updated to 0.636142
    local upper bound updated to 0.720627

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 6.03107 seconds.
    local lower bound updated to 0.720627
    local upper bound updated to 0.720627
The time obtain the optimal randomized assortment is 98.5848 (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_100.000000_103316.log"
The computation time limit for a lower bound is 85900
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 1360 rows, 1098 columns and 4372 nonzeros
Model fingerprint: 0xac0f3aa9
Variable types: 1041 continuous, 57 integer (57 binary)
Coefficient statistics:
  Matrix range     [4e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [6e-01, 1e+00]
Presolve removed 346 rows and 638 columns
Presolve time: 0.02s
Presolved: 1014 rows, 460 columns, 3920 nonzeros
Variable types: 407 continuous, 53 integer (53 binary)
Found heuristic solution: objective 0.8544663
Found heuristic solution: objective 0.8130178

Root relaxation: objective 5.724898e-01, 141 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.57249    0   22    0.81302    0.57249  29.6%     -    0s
H    0     0                       0.7784813    0.57249  26.5%     -    0s
H    0     0                       0.7684254    0.57249  25.5%     -    0s
     0     0    0.58602    0   25    0.76843    0.58602  23.7%     -    0s
H    0     0                       0.7660582    0.58602  23.5%     -    0s
H    0     0                       0.7504314    0.62087  17.3%     -    0s
     0     0    0.62087    0   24    0.75043    0.62087  17.3%     -    0s
H    0     0                       0.7286601    0.62087  14.8%     -    0s
     0     0    0.62193    0   25    0.72866    0.62193  14.6%     -    0s
H    0     0                       0.7269053    0.62193  14.4%     -    0s
     0     0    0.65158    0   24    0.72691    0.65158  10.4%     -    0s
     0     0    0.65195    0   23    0.72691    0.65195  10.3%     -    0s
H    0     0                       0.7268408    0.66039  9.14%     -    0s
H    0     0                       0.7240010    0.66039  8.79%     -    0s
H    0     0                       0.7217680    0.66039  8.50%     -    0s
     0     0    0.66039    0   25    0.72177    0.66039  8.50%     -    0s
     0     0    0.66157    0   24    0.72177    0.66157  8.34%     -    0s
     0     0    0.66327    0   26    0.72177    0.66327  8.10%     -    0s
H    0     0                       0.7206271    0.66327  7.96%     -    0s
     0     2    0.66327    0   26    0.72063    0.66327  7.96%     -    0s
H 1245   203                       0.7206271    0.70197  2.59%  15.5    3s
  2582   162     cutoff   11         0.72063    0.71187  1.22%  14.1    5s
H 2687   144                       0.7206271    0.71258  1.12%  14.0    5s

Cutting planes:
  Implied bound: 152
  MIR: 49
  Flow cover: 127
  RLT: 13
  Relax-and-lift: 68

Explored 3160 nodes (44777 simplex iterations) in 6.15 seconds (3.74 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.720627 0.720627 0.720627 ... 0.766058

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

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

        Job resource usage summary 

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

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