origin 3GB
LOG:  begin index sort: unique = f, workMem = 3145728, randomAccess = f
LOG:  bttext_abbrev: abbrev_distinct after 160: 1.000489 (key_distinct: 40.802210, norm_abbrev_card: 0.006253, prop_card: 0.200000)
LOG:  bttext_abbrev: aborted abbreviation at 160 (abbrev_distinct: 1.000489, key_distinct: 40.802210, prop_card: 0.200000)
LOG:  switching to external sort with 11235 tapes: CPU 6.22s/12.77u sec elapsed 21.32 sec
LOG:  finished writing run 1 to tape 0: CPU 17.17s/460.94u sec elapsed 484.26 sec
LOG:  finished writing run 2 to tape 1: CPU 31.61s/1207.46u sec elapsed 1252.56 sec
LOG:  finished writing run 3 to tape 2: CPU 46.66s/2028.29u sec elapsed 2095.86 sec
LOG:  finished writing run 4 to tape 3: CPU 61.73s/2857.82u sec elapsed 2947.78 sec
LOG:  finished writing run 5 to tape 4: CPU 77.14s/3704.05u sec elapsed 3818.00 sec
LOG:  performsort starting: CPU 90.25s/4419.88u sec elapsed 4553.66 sec
LOG:  finished writing run 6 to tape 5: CPU 91.21s/4510.76u sec elapsed 4645.75 sec
LOG:  finished writing final run 7 to tape 6: CPU 93.98s/4796.14u sec elapsed 4934.17 sec
LOG:  performsort done (except 7-way final merge): CPU 95.75s/4810.80u sec elapsed 4951.83 sec
LOG:  external sort ended, 2138004 disk blocks used: CPU 186.27s/5412.53u sec elapsed 5711.58 sec
LOG:  duration: 5713079.171 ms CPU 187.33s/5412.54u sec elapsed 5713.07 sec