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 34.2859 seconds.
    local lower bound updated to 0.269245
    local upper bound updated to 0.484503

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.51711
    local lower bound updated to 0.501755

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.51196
    local lower bound updated to 0.504599

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 2.50611 seconds.
    local lower bound updated to 0.36645
    local upper bound updated to 0.486851

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.51315 seconds.
    local lower bound updated to 0.480543
    local upper bound updated to 0.481114

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 6.37734 seconds.
    local lower bound updated to 0.480543
    local upper bound updated to 0.481046

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.491453
    local lower bound updated to 0.486836

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 2.52025 seconds.
    local lower bound updated to 0.385618
    local upper bound updated to 0.486836

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 3.13897 seconds.
    local lower bound updated to 0.484116
    local upper bound updated to 0.486107

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 9.4114 seconds.
    local lower bound updated to 0.484753
    local upper bound updated to 0.485908

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 2.91584 seconds.
    local lower bound updated to 0.484753
    local upper bound updated to 0.485718

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 7.8684 seconds.
    local lower bound updated to 0.485073
    local upper bound updated to 0.485556

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 6.94574 seconds.
    local lower bound updated to 0.485381
    local upper bound updated to 0.485473

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.489683
    local lower bound updated to 0.485473

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.489683
    local lower bound updated to 0.485818

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 6.95962 seconds.
    local lower bound updated to 0.399947
    local upper bound updated to 0.485818

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 8.14138 seconds.
    local lower bound updated to 0.48455
    local upper bound updated to 0.485564

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 13.5027 seconds.
    local lower bound updated to 0.485538
    local upper bound updated to 0.485564

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.489152
    local lower bound updated to 0.485564

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 11.3154 seconds.
    local lower bound updated to 0.411584
    local upper bound updated to 0.485564

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 23.0482 seconds.
    local lower bound updated to 0.485564
    local upper bound updated to 0.485564
The time obtain the optimal randomized assortment is 171.546 (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_19_4_100.000000_104420.log"
The computation time limit for a lower bound is 85826
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 1908 rows, 1402 columns and 6420 nonzeros
Model fingerprint: 0x332eb045
Variable types: 1329 continuous, 73 integer (73 binary)
Coefficient statistics:
  Matrix range     [6e-04, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 365 rows and 661 columns
Presolve time: 0.01s
Presolved: 1543 rows, 741 columns, 5969 nonzeros
Variable types: 671 continuous, 70 integer (70 binary)
Found heuristic solution: objective 0.6838058
Found heuristic solution: objective 0.6027048

Root relaxation: objective 4.188706e-01, 180 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.41887    0   26    0.60270    0.41887  30.5%     -    0s
H    0     0                       0.5995807    0.41887  30.1%     -    0s
H    0     0                       0.5542587    0.42001  24.2%     -    0s
     0     0    0.42086    0   28    0.55426    0.42086  24.1%     -    0s
     0     0    0.44020    0   32    0.55426    0.44020  20.6%     -    0s
H    0     0                       0.4917279    0.44020  10.5%     -    0s
     0     0    0.44020    0   32    0.49173    0.44020  10.5%     -    0s
     0     0    0.44723    0   32    0.49173    0.44723  9.05%     -    0s
H    0     0                       0.4855636    0.44733  7.87%     -    0s
     0     0    0.44733    0   32    0.48556    0.44733  7.87%     -    0s
     0     0    0.45303    0   32    0.48556    0.45303  6.70%     -    0s
     0     0    0.45328    0   32    0.48556    0.45328  6.65%     -    0s
     0     0    0.45411    0   31    0.48556    0.45411  6.48%     -    0s
     0     2    0.45411    0   31    0.48556    0.45411  6.48%     -    1s
  1034   293    0.48391   14    4    0.48556    0.46448  4.34%  16.6    5s
  3774   622     cutoff   16         0.48556    0.47098  3.00%  16.7   10s
H 3812   622                       0.4855636    0.47109  2.98%  16.7   10s
  5545   653    0.47545   11   18    0.48556    0.47358  2.47%  16.4   15s
H 6321   637                       0.4855636    0.47443  2.29%  16.2   16s
  7478   526    0.48135   14   20    0.48556    0.47612  1.95%  16.1   20s

Cutting planes:
  Implied bound: 252
  MIR: 77
  Flow cover: 147
  RLT: 28
  Relax-and-lift: 108
  PSD: 1

Explored 10020 nodes (159993 simplex iterations) in 22.97 seconds (29.74 work units)
Thread count was 4 (of 48 available processors)

Solution count 7: 0.485564 0.485564 0.491728 ... 0.683806

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

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

        Job resource usage summary 

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

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