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.436487 seconds.
    local lower bound updated to 0.31154
    local upper bound updated to 0.606198

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.647293
    local lower bound updated to 0.635482

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.647293
    local lower bound updated to 0.640205

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.644004
    local lower bound updated to 0.642238

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 3.16835 seconds.
    local lower bound updated to 0.433065
    local upper bound updated to 0.608216

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 6.26998 seconds.
    local lower bound updated to 0.577178
    local upper bound updated to 0.597425

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 1.38763 seconds.
    local lower bound updated to 0.593808
    local upper bound updated to 0.595573

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.643346
    local lower bound updated to 0.63505

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.643346
    local lower bound updated to 0.635624

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.637804
    local lower bound updated to 0.635794

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 65.0377 seconds.
    local lower bound updated to 0.512221
    local upper bound updated to 0.635794

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 2.67479 seconds.
    local lower bound updated to 0.579236
    local upper bound updated to 0.615718

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 7.43863 seconds.
    local lower bound updated to 0.600636
    local upper bound updated to 0.60795

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 41.043 seconds.
    local lower bound updated to 0.600636
    local upper bound updated to 0.601749

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 5.49105 seconds.
    local lower bound updated to 0.600636
    local upper bound updated to 0.601667

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 7.55892 seconds.
    local lower bound updated to 0.600636
    local upper bound updated to 0.601598

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.625625
    local lower bound updated to 0.609526

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.623324
    local lower bound updated to 0.60987

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.617408
    local lower bound updated to 0.612917

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.617408
    local lower bound updated to 0.613668

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.617408
    local lower bound updated to 0.614614

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 64.8828 seconds.
    local lower bound updated to 0.534227
    local upper bound updated to 0.613988

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.26335 seconds.
    local lower bound updated to 0.583808
    local upper bound updated to 0.609686

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 14.7824 seconds.
    local lower bound updated to 0.590828
    local upper bound updated to 0.609198

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 51.0634 seconds.
    local lower bound updated to 0.60178
    local upper bound updated to 0.608162

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 31.0507 seconds.
    local lower bound updated to 0.603053
    local upper bound updated to 0.607718

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 41.2653 seconds.
    local lower bound updated to 0.603053
    local upper bound updated to 0.607593

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 44.2101 seconds.
    local lower bound updated to 0.604465
    local upper bound updated to 0.607443

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 34.8237 seconds.
    local lower bound updated to 0.604465
    local upper bound updated to 0.607431

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 55.9644 seconds.
    local lower bound updated to 0.604818
    local upper bound updated to 0.606972

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 29.1118 seconds.
    local lower bound updated to 0.604818
    local upper bound updated to 0.606817

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 36.6762 seconds.
    local lower bound updated to 0.606429
    local upper bound updated to 0.6068

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 41.6916 seconds.
    local lower bound updated to 0.6068
    local upper bound updated to 0.6068
The Primal step takes 448.857 (S)
lower bound updated to 0.6068

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.619973
    local lower bound updated to 0.6068

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.609852
    local lower bound updated to 0.606974

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.609852
    local lower bound updated to 0.607394

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.609681
    local lower bound updated to 0.607589

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.609681
    local lower bound updated to 0.608432

    ++ RESTRICTED DUAL ITERATION 6 ++
    local upper bound updated to 0.609681
    local lower bound updated to 0.608469

    ++ RESTRICTED DUAL ITERATION 7 ++
    local upper bound updated to 0.609681
    local lower bound updated to 0.608566

    ++ RESTRICTED DUAL ITERATION 8 ++
    local upper bound updated to 0.609397
    local lower bound updated to 0.608589

    ++ RESTRICTED DUAL ITERATION 9 ++
    local upper bound updated to 0.609037
    local lower bound updated to 0.60859

    ++ RESTRICTED DUAL ITERATION 10 ++
    local upper bound updated to 0.60859
    local lower bound updated to 0.60859
The Dual step takes 20.1345 (S)
upper bound updated to 0.60859
*** OUTER ITERATION 4 ***

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.023 seconds.
    local lower bound updated to 0.55795
    local upper bound updated to 0.60859

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.598953
    local upper bound updated to 0.608066

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.036 seconds.
    local lower bound updated to 0.604591
    local upper bound updated to 0.607866

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 100.032 seconds.
    local lower bound updated to 0.604591
    local upper bound updated to 0.607748

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 81.8043 seconds.
    local lower bound updated to 0.605163
    local upper bound updated to 0.607588

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.605163
    local upper bound updated to 0.607526

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 100.025 seconds.
    local lower bound updated to 0.605384
    local upper bound updated to 0.607511

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 100.031 seconds.
    local lower bound updated to 0.606364
    local upper bound updated to 0.607509

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

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 100.043 seconds.
    local lower bound updated to 0.606749
    local upper bound updated to 0.607459

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 100.029 seconds.
    local lower bound updated to 0.606915
    local upper bound updated to 0.607437

    ++ RESTRICTED PRIMAL ITERATION 12 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.606915
    local upper bound updated to 0.607401

    ++ RESTRICTED PRIMAL ITERATION 13 ++
One worst-case parameter optimization takes 100.027 seconds.
    local lower bound updated to 0.606915
    local upper bound updated to 0.60739

    ++ RESTRICTED PRIMAL ITERATION 14 ++
One worst-case parameter optimization takes 100.021 seconds.
    local lower bound updated to 0.606915
    local upper bound updated to 0.607383

    ++ RESTRICTED PRIMAL ITERATION 15 ++
One worst-case parameter optimization takes 100.031 seconds.
    local lower bound updated to 0.607383
    local upper bound updated to 0.607383
The Primal step takes 1482.35 (S)
lower bound updated to 0.607383

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.617567
    local lower bound updated to 0.607383

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.60801
    local lower bound updated to 0.607423

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.60744
    local lower bound updated to 0.607423

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.60744
    local lower bound updated to 0.607423

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.037 seconds.
    local lower bound updated to 0.563592
    local upper bound updated to 0.607423

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.036 seconds.
    local lower bound updated to 0.607063
    local upper bound updated to 0.607421

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 100.033 seconds.
    local lower bound updated to 0.60742
    local upper bound updated to 0.607421
The Primal step takes 300.144 (S)
lower bound updated to 0.60742

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.616978
    local lower bound updated to 0.607421

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 100.032 seconds.
    local lower bound updated to 0.563592
    local upper bound updated to 0.607421

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 100.03 seconds.
    local lower bound updated to 0.60742
    local upper bound updated to 0.607421
The time obtain the optimal randomized assortment is 2628.9 (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_100516.log"
The computation time limit for a lower bound is 83366
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 3920 rows, 3178 columns and 12521 nonzeros
Model fingerprint: 0x92e6075c
Variable types: 3121 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 1151 rows and 1995 columns
Presolve time: 0.05s
Presolved: 2769 rows, 1183 columns, 10748 nonzeros
Variable types: 1128 continuous, 55 integer (55 binary)
Found heuristic solution: objective 0.7003105
Found heuristic solution: objective 0.6798765
Found heuristic solution: objective 0.6786463

Root relaxation: objective 4.457034e-01, 329 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.44570    0   25    0.67865    0.44570  34.3%     -    0s
H    0     0                       0.6768497    0.44570  34.2%     -    0s
H    0     0                       0.6588882    0.46407  29.6%     -    0s
H    0     0                       0.6343244    0.46407  26.8%     -    0s
     0     0    0.46407    0   23    0.63432    0.46407  26.8%     -    0s
     0     0    0.46407    0   23    0.63432    0.46407  26.8%     -    0s
     0     0    0.50998    0   22    0.63432    0.50998  19.6%     -    0s
H    0     0                       0.6216025    0.50998  18.0%     -    0s
     0     0    0.51002    0   22    0.62160    0.51002  17.9%     -    0s
H    0     0                       0.6198816    0.52262  15.7%     -    0s
     0     0    0.52262    0   22    0.61988    0.52262  15.7%     -    0s
     0     0    0.52339    0   24    0.61988    0.52339  15.6%     -    0s
     0     0    0.53035    0   23    0.61988    0.53035  14.4%     -    0s
H    0     0                       0.6136494    0.53035  13.6%     -    0s
     0     0    0.53125    0   24    0.61365    0.53125  13.4%     -    0s
     0     0    0.53333    0   24    0.61365    0.53333  13.1%     -    1s
H    0     0                       0.6074205    0.53333  12.2%     -    3s
     0     2    0.53333    0   24    0.60742    0.53333  12.2%     -    3s
   199   121    0.60730   19    3    0.60742    0.54272  10.7%  40.3    5s
   684   294    0.57128    8   13    0.60742    0.55054  9.36%  34.2   10s
  1462   577     cutoff   17         0.60742    0.55738  8.24%  32.9   15s
H 1802   636                       0.6074205    0.55826  8.09%  32.0   17s
  2319   768    0.57313   10   11    0.60742    0.56008  7.79%  31.0   20s
  3118   949    0.57779   13   24    0.60742    0.56324  7.27%  30.1   27s
  3137   962    0.56503   11   24    0.60742    0.56324  7.27%  29.9   30s
  3500  1012    0.59154   27   12    0.60742    0.56324  7.27%  32.7   36s
  4096   945    0.58361   24   11    0.60742    0.56324  7.27%  33.3   40s
  5079   819    0.59749   22   10    0.60742    0.56891  6.34%  33.8   45s
  5782   718     cutoff   29         0.60742    0.57116  5.97%  34.1   50s
  6880   754     cutoff   27         0.60742    0.57399  5.50%  34.3   57s
  7234   828     cutoff   27         0.60742    0.57484  5.36%  34.4   60s
  8155   921    0.60102   26    6    0.60742    0.57656  5.08%  34.6   65s
H 8182   921                       0.6074205    0.57656  5.08%  34.6   65s
H 9046   992                       0.6074205    0.57793  4.86%  34.7   67s
  9507  1062    0.59378   22   13    0.60742    0.57880  4.71%  34.8   70s
 10411  1128 infeasible   28         0.60742    0.57986  4.54%  34.7   76s
 11802  1202    0.58614   22   12    0.60742    0.58152  4.26%  34.5   81s
 13074  1221    0.59195   22   11    0.60742    0.58321  3.99%  34.3   86s
 14437  1261     cutoff   28         0.60742    0.58450  3.77%  34.3   90s
 16534  1267    0.60350   28    6    0.60742    0.58620  3.49%  34.1   97s
H16667  1267                       0.6074205    0.58621  3.49%  34.1   97s
 18045  1242    0.59472   24   10    0.60742    0.58768  3.25%  34.0  101s
 20510  1150    0.60663   26    4    0.60742    0.58950  2.95%  33.8  106s
H21725  1084                       0.6074205    0.58996  2.87%  33.7  109s
 21896  1072 infeasible   28         0.60742    0.59020  2.84%  33.7  110s
 23285   979     cutoff   28         0.60742    0.59187  2.56%  33.5  116s
H23448   979                       0.6074205    0.59194  2.55%  33.5  116s
H24474   901                       0.6074205    0.59247  2.46%  33.4  118s
 24475   851 infeasible   29         0.60742    0.59250  2.46%  33.4  120s
H25501   723                       0.6074205    0.59381  2.24%  33.3  123s
 25928   646    0.60074   27    8    0.60742    0.59427  2.17%  33.3  125s
H27517   391                       0.6074205    0.59638  1.82%  33.1  128s
 28313     0     cutoff   24         0.60742    0.59969  1.27%  33.0  130s

Cutting planes:
  Implied bound: 363
  MIR: 211
  Flow cover: 555
  RLT: 77
  Relax-and-lift: 79

Explored 28591 nodes (944004 simplex iterations) in 130.21 seconds (105.35 work units)
Thread count was 4 (of 48 available processors)

Solution count 10: 0.60742 0.60742 0.607421 ... 0.658888

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

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

        Job resource usage summary 

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

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