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 1.00328 seconds.
    local lower bound updated to 0.247091
    local upper bound updated to 0.468202

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.558754
    local lower bound updated to 0.548684

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 8.77514 seconds.
    local lower bound updated to 0.331975
    local upper bound updated to 0.479915

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 0.6079 seconds.
    local lower bound updated to 0.361876
    local upper bound updated to 0.476956

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 4.51312 seconds.
    local lower bound updated to 0.446677
    local upper bound updated to 0.469536

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 4.62871 seconds.
    local lower bound updated to 0.456777
    local upper bound updated to 0.456817

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 1.96003 seconds.
    local lower bound updated to 0.456777
    local upper bound updated to 0.456805

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 2.7504 seconds.
    local lower bound updated to 0.456777
    local upper bound updated to 0.456786
The Primal step takes 23.2472 (S)
lower bound updated to 0.456777

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.550988
    local lower bound updated to 0.47414

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.495567
    local lower bound updated to 0.482202

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.492271
    local lower bound updated to 0.485839

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.489486
    local lower bound updated to 0.488285

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 11.9882 seconds.
    local lower bound updated to 0.383812
    local upper bound updated to 0.475828

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 36.9318 seconds.
    local lower bound updated to 0.472221
    local upper bound updated to 0.475763

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 45.0336 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.474644

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 31.7702 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.474477

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 37.746 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.474233

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 37.5149 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.473617

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 36.0733 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.473591

    ++ RESTRICTED PRIMAL ITERATION 8 ++
One worst-case parameter optimization takes 31.713 seconds.
    local lower bound updated to 0.472689
    local upper bound updated to 0.473495

    ++ RESTRICTED PRIMAL ITERATION 9 ++
One worst-case parameter optimization takes 35.6931 seconds.
    local lower bound updated to 0.472787
    local upper bound updated to 0.473387

    ++ RESTRICTED PRIMAL ITERATION 10 ++
One worst-case parameter optimization takes 37.3378 seconds.
    local lower bound updated to 0.4732
    local upper bound updated to 0.47335

    ++ RESTRICTED PRIMAL ITERATION 11 ++
One worst-case parameter optimization takes 41.2348 seconds.
    local lower bound updated to 0.4732
    local upper bound updated to 0.473248

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

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.516074
    local lower bound updated to 0.474613

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.483421
    local lower bound updated to 0.475204

    ++ RESTRICTED DUAL ITERATION 3 ++
    local upper bound updated to 0.483421
    local lower bound updated to 0.475525

    ++ RESTRICTED DUAL ITERATION 4 ++
    local upper bound updated to 0.481434
    local lower bound updated to 0.475595

    ++ RESTRICTED DUAL ITERATION 5 ++
    local upper bound updated to 0.480209
    local lower bound updated to 0.475667

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 30.2494 seconds.
    local lower bound updated to 0.414373
    local upper bound updated to 0.475667

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.0296 seconds.
    local lower bound updated to 0.47251
    local upper bound updated to 0.475111

    ++ RESTRICTED PRIMAL ITERATION 3 ++
One worst-case parameter optimization takes 60.0254 seconds.
    local lower bound updated to 0.473379
    local upper bound updated to 0.474867

    ++ RESTRICTED PRIMAL ITERATION 4 ++
One worst-case parameter optimization takes 60.0196 seconds.
    local lower bound updated to 0.473379
    local upper bound updated to 0.474808

    ++ RESTRICTED PRIMAL ITERATION 5 ++
One worst-case parameter optimization takes 60.0187 seconds.
    local lower bound updated to 0.474
    local upper bound updated to 0.474774

    ++ RESTRICTED PRIMAL ITERATION 6 ++
One worst-case parameter optimization takes 60.0203 seconds.
    local lower bound updated to 0.474
    local upper bound updated to 0.47474

    ++ RESTRICTED PRIMAL ITERATION 7 ++
One worst-case parameter optimization takes 60.016 seconds.
    local lower bound updated to 0.474739
    local upper bound updated to 0.47474
The Primal step takes 390.414 (S)
lower bound updated to 0.474739

    ++ RESTRICTED DUAL ITERATION 1 ++
    local upper bound updated to 0.508071
    local lower bound updated to 0.47474

    ++ RESTRICTED DUAL ITERATION 2 ++
    local upper bound updated to 0.475529
    local lower bound updated to 0.474744

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

    ++ RESTRICTED PRIMAL ITERATION 1 ++
One worst-case parameter optimization takes 29.4113 seconds.
    local lower bound updated to 0.41511
    local upper bound updated to 0.47474

    ++ RESTRICTED PRIMAL ITERATION 2 ++
One worst-case parameter optimization takes 60.024 seconds.
    local lower bound updated to 0.474739
    local upper bound updated to 0.47474
The time obtain the optimal randomized assortment is 944.893 (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_60.000000_64620.log"
The computation time limit for a lower bound is 85049
The model output flag is 1
Gurobi Optimizer version 9.5.1 build v9.5.1rc2 (linux64)
Thread count: 128 physical cores, 256 logical processors, using up to 4 threads
Optimize a model with 3048 rows, 2232 columns and 10223 nonzeros
Model fingerprint: 0x72c054e7
Variable types: 2159 continuous, 73 integer (73 binary)
Coefficient statistics:
  Matrix range     [1e-03, 1e+00]
  Objective range  [1e+00, 1e+00]
  Bounds range     [1e+00, 1e+00]
  RHS range        [3e-01, 1e+00]
Presolve removed 584 rows and 1088 columns
Presolve time: 0.03s
Presolved: 2464 rows, 1144 columns, 9603 nonzeros
Variable types: 1073 continuous, 71 integer (71 binary)
Found heuristic solution: objective 0.6479532
Found heuristic solution: objective 0.6453069
Found heuristic solution: objective 0.6291371

Root relaxation: objective 3.462781e-01, 310 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.34628    0   19    0.62914    0.34628  45.0%     -    0s
H    0     0                       0.5868484    0.34628  41.0%     -    0s
H    0     0                       0.5843674    0.34628  40.7%     -    0s
H    0     0                       0.5790091    0.37361  35.5%     -    0s
     0     0    0.37361    0   22    0.57901    0.37361  35.5%     -    0s
H    0     0                       0.5294393    0.37361  29.4%     -    0s
     0     0    0.40008    0   33    0.52944    0.40008  24.4%     -    0s
H    0     0                       0.4781391    0.40008  16.3%     -    0s
     0     0    0.40118    0   33    0.47814    0.40118  16.1%     -    0s
     0     0    0.40842    0   32    0.47814    0.40842  14.6%     -    0s
H    0     0                       0.4747395    0.41021  13.6%     -    0s
     0     0    0.41021    0   33    0.47474    0.41021  13.6%     -    0s
     0     0    0.41654    0   33    0.47474    0.41654  12.3%     -    0s
     0     0    0.41724    0   34    0.47474    0.41724  12.1%     -    0s
     0     0    0.41807    0   33    0.47474    0.41807  11.9%     -    0s
     0     2    0.41807    0   33    0.47474    0.41807  11.9%     -    2s
   810   337     cutoff   17         0.47474    0.43394  8.59%  35.0    5s
H  853   338                       0.4747395    0.43458  8.46%  35.0    5s
H 1215   442                       0.4747395    0.43657  8.04%  34.5    5s
H 2093   678                       0.4747395    0.44154  6.99%  33.0    7s
H 2099   647                       0.4747395    0.44154  6.99%  32.9   10s
H 2376   673                       0.4747395    0.44154  6.99%  35.7   11s
  2795   682    0.45619   20   16    0.47474    0.44154  6.99%  36.5   15s
  3685   656    0.44922   20   26    0.47474    0.44578  6.10%  38.3   20s
  4671   763     cutoff   23         0.47474    0.44929  5.36%  39.7   26s
H 5235   826                       0.4747395    0.45060  5.09%  40.5   28s
  5291   835    0.47304   21   17    0.47474    0.45076  5.05%  40.5   30s
  6130   936    0.46001   22   20    0.47474    0.45277  4.63%  40.7   35s
  6994   985     cutoff   26         0.47474    0.45404  4.36%  40.7   40s
  7658  1032    0.45666   19   24    0.47474    0.45535  4.08%  40.7   45s
  8889  1051    0.47437   28   10    0.47474    0.45676  3.79%  40.5   50s
 10129  1042    0.47255   22   14    0.47474    0.45836  3.45%  40.5   55s
 11698   988     cutoff   25         0.47474    0.45976  3.15%  39.9   60s
H12200   964                       0.4747395    0.46036  3.03%  39.8   62s
 12651   890    0.46750   19   26    0.47474    0.46094  2.91%  39.8   65s
 14337   685    0.47454   26    8    0.47474    0.46286  2.50%  39.2   70s
 16641   168    0.47464   29    4    0.47474    0.46658  1.72%  38.5   76s

Cutting planes:
  Gomory: 1
  Implied bound: 309
  MIR: 140
  Flow cover: 374
  RLT: 91
  Relax-and-lift: 114

Explored 17355 nodes (663090 simplex iterations) in 76.67 seconds (79.65 work units)
Thread count was 4 (of 256 available processors)

Solution count 10: 0.474739 0.474739 0.478139 ... 0.647953

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

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

        Job resource usage summary 

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

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