Discussion related to cado-nfs

[Христофор Бобров <hbobrov@yandex.ru>]

Hello! For some numbers, the error "ERROR: all solutions have zero 
contribution on the RHS vectors -- we will just output right kernel vectors 
(maybe use allow_zero_on_rhs ?)" occurs when solving the linear stage. When I 
run with the allow_zero_on_rhs=1 parameter, the solution is 0, and many 
logarithms from the factor base are not found. When trying to increase the 
factor base and the number of relationships, the error remains. How to 
configure the settings to avoid the case.
I attach to the letter a file with log files for the number of 50 decimal 
digit. 50_1 with allow_zero_on_rhs = 0 50_2 with allow_zero_on_rhs = 1.
-- 
Best regards
Бобров Х.
PID22632 2017-04-05 15:50:36,930 Info:root: Command line parameters: 
./cado-nfs.py -dlp -ell 15707963267948966192313216916397514420985847001161 
target=24509473578884360875565352586814397447467113190347 
31415926535897932384626433832795028841971694002323 server.ssl=no -w 
/home/user/dlp/DLP50_1
PID22632 2017-04-05 15:50:36,931 Debug:root: Root parameter dictionary:
N = 31415926535897932384626433832795028841971694002323
descent_hint = /home/user/cado/cado_mpi/share/cado-nfs-2.2.1/dlp/p50.hint
dlp = True
ell = 15707963267948966192313216916397514420985847001161
gfpext = 1
lim0 = 600000
lim1 = 600000
lpb0 = 22
lpb1 = 22
name = p50
target = 24509473578884360875565352586814397447467113190347
linalg.allow_zero_on_rhs = 1
server.ssl = no
slaves.basepath = /home/user/dlp/DLP50_1/client
slaves.hostnames = localhost
slaves.nrclients = 6
slaves.scriptpath = /home/user/cado/cado_mpi/bin
tasks.I = 11
tasks.execpath = /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1
tasks.threads = 11
tasks.workdir = /home/user/dlp/DLP50_1
tasks.descent.I = 11
tasks.descent.init_I = 11
tasks.descent.init_lim = 100000
tasks.descent.init_lpb = 24
tasks.descent.init_mfb = 48
tasks.descent.init_ncurves = 10
tasks.descent.init_tkewness = 200000
tasks.descent.lim0 = 100000
tasks.descent.lim1 = 100000
tasks.descent.lpb0 = 22
tasks.descent.lpb1 = 22
tasks.descent.mfb0 = 44
tasks.descent.mfb1 = 44
tasks.filter.maxlevel = 15
tasks.filter.target_density = 100
tasks.linalg.bwc.cpubinding = false
tasks.linalg.bwc.interval = 1000
tasks.polyselect.P = 420
tasks.polyselect.admax = 1000
tasks.polyselect.adrange = 500
tasks.polyselect.degree = 4
tasks.polyselect.incr = 60
tasks.polyselect.nq = 1000
tasks.polyselect.threads = 2
tasks.reconstructlog.partial = false
tasks.sieve.lambda0 = 1.2
tasks.sieve.lambda1 = 1.2
tasks.sieve.mfb0 = 24
tasks.sieve.mfb1 = 24
tasks.sieve.qmin = 35
tasks.sieve.qrange = 1000
tasks.sieve.rels_wanted = 800000
tasks.sieve.las.threads = 2
tasks.sqrt.threads = 8
PID22632 2017-04-05 15:50:36,932 Debug:root: Writing parameter snapshot to 
/home/user/dlp/DLP50_1/p50.parameters_snapshot.0
PID22632 2017-04-05 15:50:36,933 Info:root: If this computation gets 
interrupted, it can be resumed with ./cado-nfs.py 
/home/user/dlp/DLP50_1/p50.parameters_snapshot.0
PID22632 2017-04-05 15:50:37,008 Debug:Client Launcher: Enter 
Task.__init__(slaves)
PID22632 2017-04-05 15:50:37,008 Debug:Client Launcher: state = {}
PID22632 2017-04-05 15:50:37,008 Debug:Client Launcher: self.parameters = 
MyParameters: name = None, prefix = ['slaves']
PID22632 2017-04-05 15:50:37,009 Debug:Client Launcher: params = {'run': 
True, 'hostnames': 'localhost', 'nrclients': 6, 'scriptpath': 
'/home/user/cado/cado_mpi/bin'}
PID22632 2017-04-05 15:50:37,009 Debug:Client Launcher: 
slaves.cado_nfs_client.basepath found from slaves.basepath
PID22632 2017-04-05 15:50:37,009 Debug:Client Launcher: Exit 
Task.__init__(slaves)
PID22632 2017-04-05 15:50:37,042 Info:Server Launcher: Adding 192.168.1.97 to 
whitelist to allow clients on localhost to connect
PID22632 2017-04-05 15:50:37,053 Debug:HTTP server: Found upload.py at 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:50:37,058 Info:HTTP server: Using non-threaded HTTP 
server
PID22632 2017-04-05 15:50:37,059 Info:HTTP server: Using whitelist: 
localhost,192.168.1.97
PID22632 2017-04-05 15:50:37,078 Debug:Generate Factor Base: Enter 
Task.__init__(factorbase)
PID22632 2017-04-05 15:50:37,078 Debug:Generate Factor Base: state = {}
PID22632 2017-04-05 15:50:37,078 Debug:Generate Factor Base: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'sieve']
PID22632 2017-04-05 15:50:37,078 Debug:Generate Factor Base: params = 
{'gzip': True, 'lim0': 600000, 'run': True, 'workdir': 
'/home/user/dlp/DLP50_1', 'I': 11, 'name': 'p50', 'lim1': 600000}
PID22632 2017-04-05 15:50:37,079 Debug:Generate Factor Base: 
tasks.sieve.makefb.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,079 Debug:Generate Factor Base: 
tasks.sieve.makefb.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,079 Debug:Generate Factor Base: Exit 
Task.__init__(factorbase)
PID22632 2017-04-05 15:50:37,090 Debug:Generate Free Relations: Enter 
Task.__init__(freerel)
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: state = {}
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'sieve']
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: params = 
{'gzip': True, 'run': True, 'dlp': True, 'name': 'p50', 'workdir': 
'/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: 
tasks.sieve.freerel.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: 
tasks.sieve.freerel.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,091 Debug:Generate Free Relations: 
tasks.sieve.freerel.lpb1 found from lpb1
PID22632 2017-04-05 15:50:37,092 Debug:Generate Free Relations: 
tasks.sieve.freerel.lpb0 found from lpb0
PID22632 2017-04-05 15:50:37,092 Debug:Generate Free Relations: Exit 
Task.__init__(freerel)
PID22632 2017-04-05 15:50:37,114 Debug:Lattice Sieving: Enter 
Task.__init__(sieving)
PID22632 2017-04-05 15:50:37,114 Debug:Lattice Sieving: state = {}
PID22632 2017-04-05 15:50:37,115 Debug:Lattice Sieving: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID22632 2017-04-05 15:50:37,115 Debug:Lattice Sieving: params = {'gzip': 
True, 'qrange': 1000, 'maxwu': 10, 'maxfailed': 100, 'maxtimedout': 100, 
'name': 'p50', 'maxresubmit': 5, 'run': True, 'lim1': 600000, 'workdir': 
'/home/user/dlp/DLP50_1', 'qmin': 35, 'rels_wanted': 800000, 'wutimeout': 
10800}
PID22632 2017-04-05 15:50:37,115 Debug:Lattice Sieving: tasks.sieve.las.lim0 
found from lim0
PID22632 2017-04-05 15:50:37,115 Debug:Lattice Sieving: 
tasks.sieve.las.lambda1 found from tasks.sieve.lambda1
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: 
tasks.sieve.las.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: 
tasks.sieve.las.lambda0 found from tasks.sieve.lambda0
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.mfb1 
found from tasks.sieve.mfb1
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.mfb0 
found from tasks.sieve.mfb0
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.lpb1 
found from lpb1
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: 
tasks.sieve.las.threads found from tasks.sieve.las.threads
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.I 
found from tasks.I
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.lim1 
found from lim1
PID22632 2017-04-05 15:50:37,116 Debug:Lattice Sieving: tasks.sieve.las.lpb0 
found from lpb0
PID22632 2017-04-05 15:50:37,117 Debug:Lattice Sieving: Exit 
Task.__init__(sieving)
PID22632 2017-04-05 15:50:37,171 Debug:Filtering - Duplicate Removal, 
splitting pass: Enter Task.__init__(duplicates1)
PID22632 2017-04-05 15:50:37,171 Debug:Filtering - Duplicate Removal, 
splitting pass: state = {}
PID22632 2017-04-05 15:50:37,171 Debug:Filtering - Duplicate Removal, 
splitting pass: self.parameters = MyParameters: name = None, prefix = 
['tasks', 'filter']
PID22632 2017-04-05 15:50:37,172 Debug:Filtering - Duplicate Removal, 
splitting pass: params = {'nslices_log': 1, 'run': True, 'name': 'p50', 
'workdir': '/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,172 Debug:Filtering - Duplicate Removal, 
splitting pass: tasks.filter.dup1.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,172 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Task.__init__(duplicates1)
PID22632 2017-04-05 15:50:37,210 Debug:Filtering - Duplicate Removal, removal 
pass: Enter Task.__init__(duplicates2)
PID22632 2017-04-05 15:50:37,210 Debug:Filtering - Duplicate Removal, removal 
pass: state = {}
PID22632 2017-04-05 15:50:37,210 Debug:Filtering - Duplicate Removal, removal 
pass: self.parameters = MyParameters: name = None, prefix = ['tasks', 
'filter']
PID22632 2017-04-05 15:50:37,211 Debug:Filtering - Duplicate Removal, removal 
pass: params = {'nslices_log': 1, 'run': True, 'dlp': True, 'name': 'p50', 
'workdir': '/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,211 Debug:Filtering - Duplicate Removal, removal 
pass: tasks.filter.dup2.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,211 Debug:Filtering - Duplicate Removal, removal 
pass: tasks.filter.dup2.dlp found from dlp
PID22632 2017-04-05 15:50:37,211 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Task.__init__(duplicates2)
PID22632 2017-04-05 15:50:37,242 Debug:Filtering - Singleton removal: Enter 
Task.__init__(purge)
PID22632 2017-04-05 15:50:37,242 Debug:Filtering - Singleton removal: state = 
{}
PID22632 2017-04-05 15:50:37,242 Debug:Filtering - Singleton removal: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'filter']
PID22632 2017-04-05 15:50:37,242 Debug:Filtering - Singleton removal: params 
= {'gzip': True, 'run': True, 'dlp': True, 'galois': 'none', 'workdir': 
'/home/user/dlp/DLP50_1', 'required_excess': 0.0, 'name': 'p50', 'add_ratio': 
0.01}
PID22632 2017-04-05 15:50:37,242 Debug:Filtering - Singleton removal: 
tasks.filter.purge.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,243 Debug:Filtering - Singleton removal: 
tasks.filter.purge.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,243 Debug:Filtering - Singleton removal: Exit 
Task.__init__(purge)
PID22632 2017-04-05 15:50:37,271 Debug:Polynomial Selection (size optimized): 
Enter Task.__init__(polyselect1)
PID22632 2017-04-05 15:50:37,271 Debug:Polynomial Selection (size optimized): 
state = {}
PID22632 2017-04-05 15:50:37,272 Debug:Polynomial Selection (size optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID22632 2017-04-05 15:50:37,272 Debug:Polynomial Selection (size optimized): 
params = {'nrkeep': 20, 'admin': 0, 'maxwu': 10, 'maxfailed': 100, 'N': 
31415926535897932384626433832795028841971694002323, 'name': 'p50', 
'maxresubmit': 5, 'adrange': 500, 'workdir': '/home/user/dlp/DLP50_1', 
'admax': 1000, 'maxtimedout': 100, 'run': True, 'wutimeout': 10800}
PID22632 2017-04-05 15:50:37,272 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.incr found from tasks.polyselect.incr
PID22632 2017-04-05 15:50:37,272 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,272 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.threads found from tasks.polyselect.threads
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.admax found from tasks.polyselect.admax
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.P found from tasks.polyselect.P
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.degree found from tasks.polyselect.degree
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.N found from N
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.nq found from tasks.polyselect.nq
PID22632 2017-04-05 15:50:37,273 Debug:Polynomial Selection (size optimized): 
Exit Task.__init__(polyselect1)
PID22632 2017-04-05 15:50:37,344 Debug:Polynomial Selection (root optimized): 
Enter Task.__init__(polyselect2)
PID22632 2017-04-05 15:50:37,344 Debug:Polynomial Selection (root optimized): 
state = {}
PID22632 2017-04-05 15:50:37,344 Debug:Polynomial Selection (root optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID22632 2017-04-05 15:50:37,345 Debug:Polynomial Selection (root optimized): 
params = {'maxtimedout': 100, 'maxwu': 10, 'maxfailed': 100, 'N': 
31415926535897932384626433832795028841971694002323, 'I': 11, 'lim1': 600000, 
'maxresubmit': 5, 'lim0': 600000, 'name': 'p50', 'workdir': 
'/home/user/dlp/DLP50_1', 'run': True, 'wutimeout': 10800}
PID22632 2017-04-05 15:50:37,345 Debug:Polynomial Selection (root optimized): 
tasks.polyselect.polyselect_ropt.threads found from tasks.polyselect.threads
PID22632 2017-04-05 15:50:37,345 Debug:Polynomial Selection (root optimized): 
tasks.polyselect.polyselect_ropt.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,345 Debug:Polynomial Selection (root optimized): 
Exit Task.__init__(polyselect2)
PID22632 2017-04-05 15:50:37,381 Debug:Number Theory for DLP: Enter 
Task.__init__(numbertheory)
PID22632 2017-04-05 15:50:37,381 Debug:Number Theory for DLP: state = {}
PID22632 2017-04-05 15:50:37,381 Debug:Number Theory for DLP: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'numbertheory']
PID22632 2017-04-05 15:50:37,381 Debug:Number Theory for DLP: params = 
{'run': True, 'nsm0': -1, 'name': 'p50', 'nsm1': -1, 'workdir': 
'/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,382 Debug:Number Theory for DLP: 
tasks.numbertheory.badideals.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,382 Debug:Number Theory for DLP: 
tasks.numbertheory.badideals.ell found from ell
PID22632 2017-04-05 15:50:37,382 Debug:Number Theory for DLP: Exit 
Task.__init__(numbertheory)
PID22632 2017-04-05 15:50:37,392 Debug:Filtering - Galois: Enter 
Task.__init__(filtergalois)
PID22632 2017-04-05 15:50:37,392 Debug:Filtering - Galois: state = {}
PID22632 2017-04-05 15:50:37,392 Debug:Filtering - Galois: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID22632 2017-04-05 15:50:37,393 Debug:Filtering - Galois: params = 
{'galois': 'none', 'run': True, 'name': 'p50', 'workdir': 
'/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,393 Debug:Filtering - Galois: 
tasks.filter.filter_galois.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,393 Debug:Filtering - Galois: Exit 
Task.__init__(filtergalois)
PID22632 2017-04-05 15:50:37,402 Debug:Schirokauer Maps: Enter 
Task.__init__(sm)
PID22632 2017-04-05 15:50:37,402 Debug:Schirokauer Maps: state = {}
PID22632 2017-04-05 15:50:37,402 Debug:Schirokauer Maps: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID22632 2017-04-05 15:50:37,403 Debug:Schirokauer Maps: params = {'run': 
True, 'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,403 Debug:Schirokauer Maps: 
tasks.filter.sm.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,403 Debug:Schirokauer Maps: 
tasks.filter.sm.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,403 Debug:Schirokauer Maps: tasks.filter.sm.ell 
found from ell
PID22632 2017-04-05 15:50:37,403 Debug:Schirokauer Maps: Exit 
Task.__init__(sm)
PID22632 2017-04-05 15:50:37,413 Debug:Filtering - Merging: Enter 
Task.__init__(mergedlp)
PID22632 2017-04-05 15:50:37,413 Debug:Filtering - Merging: state = {}
PID22632 2017-04-05 15:50:37,413 Debug:Filtering - Merging: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID22632 2017-04-05 15:50:37,414 Debug:Filtering - Merging: params = {'gzip': 
True, 'run': True, 'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,414 Debug:Filtering - Merging: 
tasks.filter.merge-dl.target_density found from tasks.filter.target_density
PID22632 2017-04-05 15:50:37,414 Debug:Filtering - Merging: 
tasks.filter.merge-dl.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,414 Debug:Filtering - Merging: 
tasks.filter.merge-dl.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,414 Debug:Filtering - Merging: 
tasks.filter.merge-dl.maxlevel found from tasks.filter.maxlevel
PID22632 2017-04-05 15:50:37,415 Debug:Filtering - Merging: 
tasks.filter.replay-dl.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,415 Debug:Filtering - Merging: Exit 
Task.__init__(mergedlp)
PID22632 2017-04-05 15:50:37,424 Debug:Linear Algebra: Enter 
Task.__init__(linalgdlp)
PID22632 2017-04-05 15:50:37,424 Debug:Linear Algebra: state = {}
PID22632 2017-04-05 15:50:37,424 Debug:Linear Algebra: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'linalg']
PID22632 2017-04-05 15:50:37,424 Debug:Linear Algebra: params = 
{'force_wipeout': False, 'ell': 
15707963267948966192313216916397514420985847001161, 'm': 0, 'n': 0, 
'workdir': '/home/user/dlp/DLP50_1', 'name': 'p50', 'run': True}
PID22632 2017-04-05 15:50:37,425 Debug:Linear Algebra: 
tasks.linalg.bwc.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,425 Debug:Linear Algebra: 
tasks.linalg.bwc.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,425 Debug:Linear Algebra: 
tasks.linalg.bwc.cpubinding found from tasks.linalg.bwc.cpubinding
PID22632 2017-04-05 15:50:37,425 Debug:Linear Algebra: 
tasks.linalg.bwc.interval found from tasks.linalg.bwc.interval
PID22632 2017-04-05 15:50:37,426 Debug:Linear Algebra: Exit 
Task.__init__(linalgdlp)
PID22632 2017-04-05 15:50:37,438 Debug:Logarithms Reconstruction: Enter 
Task.__init__(reconstructlog)
PID22632 2017-04-05 15:50:37,438 Debug:Logarithms Reconstruction: state = {}
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 
'reconstructlog']
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: params = 
{'run': True, 'checkdlp': True, 'name': 'p50', 'workdir': 
'/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.threads found from tasks.threads
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.partial found from 
tasks.reconstructlog.partial
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,439 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.ell found from ell
PID22632 2017-04-05 15:50:37,440 Debug:Logarithms Reconstruction: Exit 
Task.__init__(reconstructlog)
PID22632 2017-04-05 15:50:37,450 Debug:Individual logarithm: Enter 
Task.__init__(descent)
PID22632 2017-04-05 15:50:37,450 Debug:Individual logarithm: state = {}
PID22632 2017-04-05 15:50:37,450 Debug:Individual logarithm: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'descent']
PID22632 2017-04-05 15:50:37,450 Debug:Individual logarithm: params = {'run': 
True, 'execpath': '/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1', 'name': 
'p50', 'target': 24509473578884360875565352586814397447467113190347, 
'workdir': '/home/user/dlp/DLP50_1'}
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.lim0 found from tasks.descent.lim0
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.descent_hint found from descent_hint
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.init_mfb found from tasks.descent.init_mfb
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.target found from target
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.mfb0 found from tasks.descent.mfb0
PID22632 2017-04-05 15:50:37,451 Debug:Individual logarithm: 
tasks.descent.descent.execpath found from tasks.execpath
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.I found from tasks.descent.I
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.init_I found from tasks.descent.init_I
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.lim1 found from tasks.descent.lim1
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.init_ncurves found from tasks.descent.init_ncurves
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.ell found from ell
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.init_lim found from tasks.descent.init_lim
PID22632 2017-04-05 15:50:37,452 Debug:Individual logarithm: 
tasks.descent.descent.init_lpb found from tasks.descent.init_lpb
PID22632 2017-04-05 15:50:37,453 Debug:Individual logarithm: 
tasks.descent.descent.mfb1 found from tasks.descent.mfb1
PID22632 2017-04-05 15:50:37,453 Debug:Individual logarithm: 
tasks.descent.descent.lpb1 found from tasks.descent.lpb1
PID22632 2017-04-05 15:50:37,453 Debug:Individual logarithm: 
tasks.descent.descent.init_tkewness found from tasks.descent.init_tkewness
PID22632 2017-04-05 15:50:37,453 Debug:Individual logarithm: 
tasks.descent.descent.lpb0 found from tasks.descent.lpb0
PID22632 2017-04-05 15:50:37,453 Debug:Individual logarithm: Exit 
Task.__init__(descent)
PID22632 2017-04-05 15:50:37,456 Warning:Complete Factorization: Parameter 
linalg.allow_zero_on_rhs = 1 was not used anywhere
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization: Perhaps you 
meant tasks.linalg.bwc.allow_zero_on_rhs ?
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization: Parameter 
tasks.sqrt.threads = 8 was not used anywhere
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization: Perhaps you 
meant one of the following ?
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization:   
tasks.polyselect.polyselect.threads ?
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization:   
tasks.polyselect.polyselect_ropt.threads ?
PID22632 2017-04-05 15:50:37,457 Warning:Complete Factorization:   
tasks.sieve.makefb.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.sieve.freerel.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.sieve.las.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.filter.purge.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.filter.merge-dl.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.filter.sm.threads ?
PID22632 2017-04-05 15:50:37,458 Warning:Complete Factorization:   
tasks.linalg.bwc.threads ?
PID22632 2017-04-05 15:50:37,459 Warning:Complete Factorization:   
tasks.reconstructlog.reconstructlog-dl.threads ?
PID22632 2017-04-05 15:50:37,459 Warning:Complete Factorization: (If you wish 
to set all of these consistently, you may set tasks.threads)
PID22632 2017-04-05 15:50:37,459 Info:Complete Factorization: Computing 
Discrete Logs in GF(31415926535897932384626433832795028841971694002323)
PID22632 2017-04-05 15:50:37,464 Info:HTTP server: serving at 
http://192.168.1.97:53958 (0.0.0.0)
PID22632 2017-04-05 15:50:37,464 Info:HTTP server: For debugging purposes, 
the URL above can be accessed if the server.only_registered=False parameter 
is added
PID22632 2017-04-05 15:50:37,464 Info:HTTP server: You can start additional 
cado-nfs-client.py scripts with parameters: --server=http://192.168.1.97:53958
PID22632 2017-04-05 15:50:37,464 Info:HTTP server: If you want to start 
additional clients, remember to add their hosts to server.whitelist
PID22632 2017-04-05 15:50:37,465 Info:Client Launcher: Starting client id 
localhost on host localhost
PID22632 2017-04-05 15:50:37,468 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,550 Debug:Command: Process with PID 22635 
finished successfully
PID22632 2017-04-05 15:50:37,550 Debug:Command: Process with PID 22635 
stdout: b'PID: 22636\n'
PID22632 2017-04-05 15:50:37,551 Debug:Command: Process with PID 22635 
stderr: b'INFO:root:Starting client localhost\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,557 Info:Client Launcher: Starting client id 
localhost+2 on host localhost
PID22632 2017-04-05 15:50:37,559 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost+2 --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,630 Debug:Command: Process with PID 22637 
finished successfully
PID22632 2017-04-05 15:50:37,630 Debug:Command: Process with PID 22637 
stdout: b'PID: 22638\n'
PID22632 2017-04-05 15:50:37,631 Debug:Command: Process with PID 22637 
stderr: b'INFO:root:Starting client localhost+2\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,636 Info:Client Launcher: Starting client id 
localhost+3 on host localhost
PID22632 2017-04-05 15:50:37,638 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost+3 --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,707 Debug:Command: Process with PID 22639 
finished successfully
PID22632 2017-04-05 15:50:37,707 Debug:Command: Process with PID 22639 
stdout: b'PID: 22640\n'
PID22632 2017-04-05 15:50:37,707 Debug:Command: Process with PID 22639 
stderr: b'INFO:root:Starting client localhost+3\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,712 Info:Client Launcher: Starting client id 
localhost+4 on host localhost
PID22632 2017-04-05 15:50:37,714 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost+4 --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,782 Debug:Command: Process with PID 22641 
finished successfully
PID22632 2017-04-05 15:50:37,782 Debug:Command: Process with PID 22641 
stdout: b'PID: 22642\n'
PID22632 2017-04-05 15:50:37,782 Debug:Command: Process with PID 22641 
stderr: b'INFO:root:Starting client localhost+4\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,787 Info:Client Launcher: Starting client id 
localhost+5 on host localhost
PID22632 2017-04-05 15:50:37,790 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost+5 --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,856 Debug:Command: Process with PID 22651 
finished successfully
PID22632 2017-04-05 15:50:37,857 Debug:Command: Process with PID 22651 
stdout: b'PID: 22652\n'
PID22632 2017-04-05 15:50:37,857 Debug:Command: Process with PID 22651 
stderr: b'INFO:root:Starting client localhost+5\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,862 Info:Client Launcher: Starting client id 
localhost+6 on host localhost
PID22632 2017-04-05 15:50:37,865 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:53958 --daemon --clientid localhost+6 --basepath 
/home/user/dlp/DLP50_1/client
PID22632 2017-04-05 15:50:37,938 Debug:Command: Process with PID 22653 
finished successfully
PID22632 2017-04-05 15:50:37,938 Debug:Command: Process with PID 22653 
stdout: b'PID: 22654\n'
PID22632 2017-04-05 15:50:37,938 Debug:Command: Process with PID 22653 
stderr: b'INFO:root:Starting client localhost+6\nINFO:root:Python version is 
2.7.5\n'
PID22632 2017-04-05 15:50:37,944 Info:Client Launcher: Running clients: 
localhost+5 (Host localhost, PID 22652), localhost+6 (Host localhost, PID 
22654), localhost+4 (Host localhost, PID 22642), localhost+3 (Host localhost, 
PID 22640), localhost+2 (Host localhost, PID 22638), localhost (Host 
localhost, PID 22636)
PID22632 2017-04-05 15:50:37,945 Info:Polynomial Selection (size optimized): 
Starting
PID22632 2017-04-05 15:50:37,945 Debug:Polynomial Selection (size optimized): 
polyselect1.run(): Task state: {'wu_timedout': 0, 'wu_submitted': 0, 
'adnext': 0, 'wu_received': 0, 'wu_failed': 0, 'start_real_time': 0, 
'start_achievement': -1}
PID22632 2017-04-05 15:50:37,945 Info:Polynomial Selection (size optimized): 
0 polynomials in queue from previous run
PID22632 2017-04-05 15:50:37,947 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID22632 2017-04-05 15:50:37,949 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect is 
('c5c4c6fece0d48dcc30878ee38fd0fbbee5a428f', (212431668, 935665, 
1490019316.358416))
PID22632 2017-04-05 15:50:37,949 Debug:Server Launcher: Registering file name 
polyselect with target 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID22632 2017-04-05 15:50:37,954 Info:Polynomial Selection (size optimized): 
Adding workunit p50_polyselect1_0-500 to database
PID22632 2017-04-05 15:50:37,963 Info:Polynomial Selection (size optimized): 
Adding workunit p50_polyselect1_500-1000 to database
PID22632 2017-04-05 15:50:37,970 Debug:Polynomial Selection (size optimized): 
Setting last timeout check to 1491396637.970182
PID22632 2017-04-05 15:50:47,609 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect1_0-500 to client localhost
PID22632 2017-04-05 15:50:47,610 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:47,613 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID22632 2017-04-05 15:50:47,613 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:50:47,667 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect1_500-1000 to client localhost+2
PID22632 2017-04-05 15:50:47,667 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:48,061 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:48,062 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:50:48,334 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:48,334 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:50:49,351 Info:Polynomial Selection (size optimized): 
Parsed 37 polynomials, added 28 to priority queue (has 20)
PID22632 2017-04-05 15:50:49,352 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 14.310000
PID22632 2017-04-05 15:50:49,361 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_collisions': '96.13', 'stats_rawlognorm': '37 
15.44 18.08 23.34 1.54', 'stats_optlognorm': '37 15.07 17.02 18.69 1.03', 
'stats_total_time': '0.81'}
PID22632 2017-04-05 15:50:49,361 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_collisions': '96.13', 'stats_rawlognorm': '37 15.44 
18.08 23.34 1.54', 'stats_optlognorm': '37 15.07 17.02 18.69 1.03', 
'stats_total_time': '0.81'}
PID22632 2017-04-05 15:50:49,368 Info:Polynomial Selection (size optimized): 
Marking workunit p50_polyselect1_0-500 as ok (50.0% => ETA Unknown)
PID22632 2017-04-05 15:50:49,418 Info:Polynomial Selection (size optimized): 
Parsed 55 polynomials, added 4 to priority queue (has 20)
PID22632 2017-04-05 15:50:49,418 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 14.170000
PID22632 2017-04-05 15:50:49,424 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_collisions': '96.13', 'stats_rawlognorm': '55 
16.02 18.14 19.67 0.83', 'stats_optlognorm': '55 15.48 17.3 19.51 0.79', 
'stats_total_time': '1.0'}
PID22632 2017-04-05 15:50:49,424 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_collisions': '192.26', 'stats_rawlognorm': '92 15.44 
18.11586956521739 23.34 1.1689754452863546', 'stats_optlognorm': '92 15.07 
17.187391304347827 19.51 0.9047758184564135', 'stats_total_time': '1.81'}
PID22632 2017-04-05 15:50:49,425 Info:Polynomial Selection (size optimized): 
Marking workunit p50_polyselect1_500-1000 as ok (100.0% => ETA Wed Apr  5 
15:50:49 2017)
PID22632 2017-04-05 15:50:49,431 Info:Polynomial Selection (size optimized): 
Finished
PID22632 2017-04-05 15:50:49,431 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 92/15.440/18.116/23.340/1.169
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 92/15.070/17.187/19.510/0.905
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (size optimized): 
Total time: 1.81
PID22632 2017-04-05 15:50:49,432 Info:Polynomial Selection (root optimized): 
Starting
PID22632 2017-04-05 15:50:49,433 Debug:Polynomial Selection (root optimized): 
polyselect2.run(): Task state: {'wu_timedout': 0, 'wu_submitted': 0, 
'wu_received': 0, 'nr_poly_submitted': 0, 'wu_failed': 0, 'start_real_time': 
0, 'start_achievement': -1}
PID22632 2017-04-05 15:50:49,433 Info:Polynomial Selection (root optimized): 
No polynomial was previously found
PID22632 2017-04-05 15:50:49,435 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID22632 2017-04-05 15:50:49,435 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_0 is 
('8f2dbff213762e57f3023c5bbc4aab172026ebd9', (10167576, 1610, 
1491396649.433024))
PID22632 2017-04-05 15:50:49,435 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID22632 2017-04-05 15:50:49,438 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt is 
('7e6a1b83979b2a60b7ede93b78d9ac390aa308f9', (212431667, 835453, 
1490019315.899416))
PID22632 2017-04-05 15:50:49,438 Debug:Server Launcher: Registering file name 
polyselect_ropt with target 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID22632 2017-04-05 15:50:49,442 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_0 with target /home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID22632 2017-04-05 15:50:49,446 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_0 to database
PID22632 2017-04-05 15:50:49,456 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID22632 2017-04-05 15:50:49,456 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_6 is 
('b0ab07285fdefa96b7de3eda57ff4d6dd48b5f8a', (10167583, 1592, 
1491396649.455024))
PID22632 2017-04-05 15:50:49,456 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_6 with target /home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID22632 2017-04-05 15:50:49,461 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_6 to database
PID22632 2017-04-05 15:50:49,471 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID22632 2017-04-05 15:50:49,471 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12 is 
('58f5a890f28abc86440562135beb713482d2bcd0', (10167724, 1596, 
1491396649.469024))
PID22632 2017-04-05 15:50:49,471 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_12 with target 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID22632 2017-04-05 15:50:49,477 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_12 to database
PID22632 2017-04-05 15:50:49,483 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID22632 2017-04-05 15:50:49,483 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18 is 
('99b4eb3b30217ca9f21961a6ba93e9d26b9fcf78', (10210721, 534, 
1491396649.482024))
PID22632 2017-04-05 15:50:49,483 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_18 with target 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID22632 2017-04-05 15:50:49,487 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_18 to database
PID22632 2017-04-05 15:50:49,493 Debug:Polynomial Selection (root optimized): 
Setting last timeout check to 1491396649.493157
PID22632 2017-04-05 15:50:57,748 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_0 to client localhost+3
PID22632 2017-04-05 15:50:57,749 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:57,752 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_0 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID22632 2017-04-05 15:50:57,753 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_0 HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:50:57,756 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect_ropt to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID22632 2017-04-05 15:50:57,756 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect_ropt HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:50:57,823 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_6 to client localhost+4
PID22632 2017-04-05 15:50:57,823 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:57,825 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_6 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID22632 2017-04-05 15:50:57,825 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_6 HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:50:57,915 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_12 to client localhost+5
PID22632 2017-04-05 15:50:57,915 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:57,917 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_12 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID22632 2017-04-05 15:50:57,918 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_12 HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:50:58,001 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_18 to client localhost+6
PID22632 2017-04-05 15:50:58,002 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+6 HTTP/1.1" 200 -
PID22632 2017-04-05 15:50:58,007 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_18 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID22632 2017-04-05 15:50:58,007 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_18 HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:51:01,643 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:01,645 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:51:02,534 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.wo6o0nzl.opt_18: Murphy E = 
1.22e-05
PID22632 2017-04-05 15:51:02,534 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 31415926535897932384626433832795028841971694002323
skew: 586.791
c0: 26738928110117
c1: -8174235023
c2: -18337338
c3: 37182
c4: 540
Y0: -481463785014
Y1: 519052169
# MurphyE (Bf=6.00e+05,Bg=6.00e+05,area=1.26e+12) = 1.22e-05
# f(x) = 540*x^4+37182*x^3-18337338*x^2-8174235023*x+26738928110117
# g(x) = 519052169*x-481463785014

PID22632 2017-04-05 15:51:02,537 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '6.86', 'stats_rootsieve_time': 
'6.41'}
PID22632 2017-04-05 15:51:02,537 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '6.86', 'stats_rootsieve_time': '6.41'}
PID22632 2017-04-05 15:51:02,555 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_18 as ok (10.0% => ETA Unknown)
PID22632 2017-04-05 15:51:06,537 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:06,538 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:51:06,587 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.s_0ckzcs.opt_0 with 
E=1.01e-05 is no better than current best with E=1.22e-05
PID22632 2017-04-05 15:51:06,588 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '17.02', 'stats_rootsieve_time': 
'16.09'}
PID22632 2017-04-05 15:51:06,588 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '23.88', 'stats_rootsieve_time': '22.5'}
PID22632 2017-04-05 15:51:06,589 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_0 as ok (40.0% => ETA Wed Apr  5 15:51:40 
2017)
PID22632 2017-04-05 15:51:07,609 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:07,609 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:51:07,675 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:07,675 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:51:08,621 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.wxb3g1y7.opt_6: Murphy E = 
1.37e-05
PID22632 2017-04-05 15:51:08,621 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 31415926535897932384626433832795028841971694002323
skew: 754.826
c0: 141282195048885
c1: -508760385131
c2: -1287014117
c3: 572823
c4: 180
Y0: -68624570812
Y1: 985734191
# MurphyE (Bf=6.00e+05,Bg=6.00e+05,area=1.26e+12) = 1.37e-05
# f(x) = 180*x^4+572823*x^3-1287014117*x^2-508760385131*x+141282195048885
# g(x) = 985734191*x-68624570812

PID22632 2017-04-05 15:51:08,624 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '18.58', 'stats_rootsieve_time': 
'17.58'}
PID22632 2017-04-05 15:51:08,624 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '42.459999999999994', 
'stats_rootsieve_time': '40.08'}
PID22632 2017-04-05 15:51:08,625 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_6 as ok (70.0% => ETA Wed Apr  5 15:51:18 
2017)
PID22632 2017-04-05 15:51:08,635 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.9np0bo5j.opt_12 with 
E=1.14e-05 is no better than current best with E=1.37e-05
PID22632 2017-04-05 15:51:08,636 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '17.6', 'stats_rootsieve_time': 
'16.57'}
PID22632 2017-04-05 15:51:08,637 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '60.059999999999995', 
'stats_rootsieve_time': '56.65'}
PID22632 2017-04-05 15:51:08,638 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_12 as ok (100.0% => ETA Wed Apr  5 15:51:08 
2017)
PID22632 2017-04-05 15:51:08,644 Info:Polynomial Selection (root optimized): 
Finished, best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.wxb3g1y7.opt_6 has Murphy_E 
= 1.37e-05
PID22632 2017-04-05 15:51:08,645 Info:Polynomial Selection (root optimized): 
Best overall polynomial was 10-th in list after size optimization
PID22632 2017-04-05 15:51:08,651 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID22632 2017-04-05 15:51:08,651 Info:Polynomial Selection (root optimized): 
Total time: 60.06
PID22632 2017-04-05 15:51:08,651 Info:Polynomial Selection (root optimized): 
Rootsieve time: 56.65
PID22632 2017-04-05 15:51:08,652 Info:Number Theory for DLP: Starting
PID22632 2017-04-05 15:51:08,652 Debug:Number Theory for DLP: 
numbertheory.run(): Task state: {}
PID22632 2017-04-05 15:51:08,663 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/badideals -poly 
/home/user/dlp/DLP50_1/p50.poly -ell 
15707963267948966192313216916397514420985847001161 -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -badideals 
/home/user/dlp/DLP50_1/p50.badideals > 
/home/user/dlp/DLP50_1/p50.badideals.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.badideals.stderr.1
PID22632 2017-04-05 15:51:08,829 Debug:Command: Process with PID 22680 
finished successfully
PID22632 2017-04-05 15:51:08,838 Debug:Number Theory for DLP: Exit 
NumberTheoryTask.run(numbertheory)
PID22632 2017-04-05 15:51:08,839 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.16/0.165962
PID22632 2017-04-05 15:51:08,839 Info:Generate Factor Base: Starting
PID22632 2017-04-05 15:51:08,839 Debug:Generate Factor Base: 
factorbase.run(): Task state: {}
PID22632 2017-04-05 15:51:08,851 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/makefb -poly 
/home/user/dlp/DLP50_1/p50.poly -lim 600000 -maxbits 11 -out 
/home/user/dlp/DLP50_1/p50.roots.gz -t 11 > 
/home/user/dlp/DLP50_1/p50.makefb.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.makefb.stderr.1
PID22632 2017-04-05 15:51:09,169 Debug:Command: Process with PID 22681 
finished successfully
PID22632 2017-04-05 15:51:09,177 Info:Generate Factor Base: Finished
PID22632 2017-04-05 15:51:09,177 Info:Generate Factor Base: Total cpu/real 
time for makefb: 1.64/0.318573
PID22632 2017-04-05 15:51:09,177 Info:Generate Free Relations: Starting
PID22632 2017-04-05 15:51:09,178 Debug:Generate Free Relations: 
freerel.run(): Task state: {}
PID22632 2017-04-05 15:51:09,189 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/freerel -poly 
/home/user/dlp/DLP50_1/p50.poly -renumber 
/home/user/dlp/DLP50_1/p50.renumber.gz -lpb0 22 -lpb1 22 -out 
/home/user/dlp/DLP50_1/p50.freerel.gz -badideals 
/home/user/dlp/DLP50_1/p50.badideals -lcideals -t 11 > 
/home/user/dlp/DLP50_1/p50.freerel.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.freerel.stderr.1
PID22632 2017-04-05 15:51:10,849 Debug:Command: Process with PID 22732 
finished successfully
PID22632 2017-04-05 15:51:10,858 Info:Generate Free Relations: Found 12059 
free relations
PID22632 2017-04-05 15:51:10,858 Info:Generate Free Relations: Finished
PID22632 2017-04-05 15:51:10,858 Info:Generate Free Relations: Total cpu/real 
time for freerel: 14.78/1.6598
PID22632 2017-04-05 15:51:10,858 Info:Lattice Sieving: Starting
PID22632 2017-04-05 15:51:10,858 Debug:Lattice Sieving: sieving.run(): Task 
state: {'qnext': 35, 'wu_timedout': 0, 'wu_submitted': 0, 'wu_received': 0, 
'wu_failed': 0, 'start_real_time': 0, 'rels_found': 0, 'start_achievement': 
-1, 'rels_wanted': 800000}
PID22632 2017-04-05 15:51:10,859 Info:Lattice Sieving: We want 800000 
relations
PID22632 2017-04-05 15:51:10,861 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.poly
PID22632 2017-04-05 15:51:10,861 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.poly is 
('2016b206770180d5ef38bd95dc901835cebb84c7', (10210724, 340, 
1491396668.645024))
PID22632 2017-04-05 15:51:10,861 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.roots.gz
PID22632 2017-04-05 15:51:10,862 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.roots.gz is 
('717adc5358bf1e1f1f16e9d77a2fee52b4810581', (10210740, 263835, 
1491396669.168024))
PID22632 2017-04-05 15:51:10,862 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID22632 2017-04-05 15:51:10,876 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las is 
('3bb32a4292cff9c1d8e291684e33816cee97e62f', (10167295, 7121242, 
1490019316.707416))
PID22632 2017-04-05 15:51:10,877 Debug:Server Launcher: Registering file name 
las with target /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID22632 2017-04-05 15:51:10,881 Debug:Server Launcher: Registering file name 
p50.poly with target /home/user/dlp/DLP50_1/p50.poly
PID22632 2017-04-05 15:51:10,885 Debug:Server Launcher: Registering file name 
p50.roots.gz with target /home/user/dlp/DLP50_1/p50.roots.gz
PID22632 2017-04-05 15:51:10,891 Info:Lattice Sieving: Adding workunit 
p50_sieving_35-1000 to database
PID22632 2017-04-05 15:51:10,903 Info:Lattice Sieving: Adding workunit 
p50_sieving_1000-2000 to database
PID22632 2017-04-05 15:51:10,914 Info:Lattice Sieving: Adding workunit 
p50_sieving_2000-3000 to database
PID22632 2017-04-05 15:51:10,922 Info:Lattice Sieving: Adding workunit 
p50_sieving_3000-4000 to database
PID22632 2017-04-05 15:51:10,928 Info:Lattice Sieving: Adding workunit 
p50_sieving_4000-5000 to database
PID22632 2017-04-05 15:51:10,935 Info:Lattice Sieving: Adding workunit 
p50_sieving_5000-6000 to database
PID22632 2017-04-05 15:51:10,942 Info:Lattice Sieving: Adding workunit 
p50_sieving_6000-7000 to database
PID22632 2017-04-05 15:51:10,950 Info:Lattice Sieving: Adding workunit 
p50_sieving_7000-8000 to database
PID22632 2017-04-05 15:51:10,956 Info:Lattice Sieving: Adding workunit 
p50_sieving_8000-9000 to database
PID22632 2017-04-05 15:51:10,963 Info:Lattice Sieving: Adding workunit 
p50_sieving_9000-10000 to database
PID22632 2017-04-05 15:51:10,970 Debug:Lattice Sieving: Setting last timeout 
check to 1491396670.970886
PID22632 2017-04-05 15:51:11,748 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_35-1000 to client localhost+6
PID22632 2017-04-05 15:51:11,749 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+6 HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:11,752 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.poly to /home/user/dlp/DLP50_1/p50.poly
PID22632 2017-04-05 15:51:11,753 Debug:HTTP server: 127.0.0.1 "GET /p50.poly 
HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:51:11,756 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.roots.gz to /home/user/dlp/DLP50_1/p50.roots.gz
PID22632 2017-04-05 15:51:11,756 Debug:HTTP server: 127.0.0.1 "GET 
/p50.roots.gz HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:51:11,761 Debug:HTTP server: 127.0.0.1 Translated path 
/las to /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID22632 2017-04-05 15:51:11,762 Debug:HTTP server: 127.0.0.1 "GET /las 
HTTP/1.1" HTTPStatus.OK -
PID22632 2017-04-05 15:51:11,973 Info:Lattice Sieving: Adding workunit 
p50_sieving_10000-11000 to database
PID22632 2017-04-05 15:51:16,579 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_1000-2000 to client localhost+3
PID22632 2017-04-05 15:51:16,579 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:16,989 Info:Lattice Sieving: Adding workunit 
p50_sieving_11000-12000 to database
PID22632 2017-04-05 15:51:17,660 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_2000-3000 to client localhost+5
PID22632 2017-04-05 15:51:17,660 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:17,718 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_3000-4000 to client localhost+4
PID22632 2017-04-05 15:51:17,718 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:18,004 Info:Lattice Sieving: Adding workunit 
p50_sieving_12000-13000 to database
PID22632 2017-04-05 15:51:18,036 Info:Lattice Sieving: Adding workunit 
p50_sieving_13000-14000 to database
PID22632 2017-04-05 15:51:18,139 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_4000-5000 to client localhost
PID22632 2017-04-05 15:51:18,140 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:18,438 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_5000-6000 to client localhost+2
PID22632 2017-04-05 15:51:18,439 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID22632 2017-04-05 15:51:19,071 Info:Lattice Sieving: Adding workunit 
p50_sieving_14000-15000 to database
PID22632 2017-04-05 15:51:19,111 Info:Lattice Sieving: Adding workunit 
p50_sieving_15000-16000 to database
PID22632 2017-04-05 15:52:40,646 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:52:40,647 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:52:42,457 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_6000-7000 to client localhost+2
PID22632 2017-04-05 15:52:42,460 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID22632 2017-04-05 15:52:42,599 Info:Lattice Sieving: Adding workunit 
p50_sieving_16000-17000 to database
PID22632 2017-04-05 15:52:42,631 Debug:Lattice Sieving: stderr is: b"# 
Average J=991 for 100 special-q's, max bucket fill 0.617333\n# Discarded 0 
special-q's out of 100 pushed\n# Total cpu time 81.48s [norm 0.06+1.1, 
sieving 26.8 (20.6 + 1.8 + 4.3), factor 53.5 (51.3 + 2.2)]\n# Total elapsed 
time 81.28s, per special-q 0.812778s, per relation 0.000141019s\n# 
PeakMemusage (MB) = 229 \n# Total 576359 reports [0.000141s/r, 5763.6r/sq]\n"
PID22632 2017-04-05 15:52:42,640 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_total_cpu_time': '81.48', 'stats_max_bucket_fill': '0.617333', 
'stats_avg_J': '991.0 100'}
PID22632 2017-04-05 15:52:42,641 Debug:Lattice Sieving: Combined stats: 
{'stats_total_cpu_time': '81.48', 'stats_max_bucket_fill': '0.617333', 
'stats_avg_J': '991.0 100'}
PID22632 2017-04-05 15:52:42,642 Info:Lattice Sieving: Found 576359 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.akf_u3sc.gz', total is 
now 576359/800000
PID22632 2017-04-05 15:52:42,669 Info:Lattice Sieving: Marking workunit 
p50_sieving_5000-6000 as ok (72.0% => ETA Unknown)
PID22632 2017-04-05 15:53:00,653 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:00,657 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:53:02,794 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_7000-8000 to client localhost
PID22632 2017-04-05 15:53:02,795 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:02,847 Info:Lattice Sieving: Adding workunit 
p50_sieving_17000-18000 to database
PID22632 2017-04-05 15:53:02,888 Debug:Lattice Sieving: stderr is: b"# 
Average J=1002 for 122 special-q's, max bucket fill 0.633280\n# Discarded 0 
special-q's out of 122 pushed\n# Total cpu time 102.25s [norm 0.05+1.3, 
sieving 33.1 (25.5 + 2.4 + 5.2), factor 67.8 (64.9 + 2.9)]\n# Total elapsed 
time 101.39s, per special-q 0.831047s, per relation 0.000136929s\n# 
PeakMemusage (MB) = 229 \n# Total 740441 reports [0.000138s/r, 6069.2r/sq]\n"
PID22632 2017-04-05 15:53:02,891 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_total_cpu_time': '102.25', 'stats_max_bucket_fill': '0.63328', 
'stats_avg_J': '1002.0 122'}
PID22632 2017-04-05 15:53:02,892 Debug:Lattice Sieving: Combined stats: 
{'stats_total_cpu_time': '183.73000000000002', 'stats_max_bucket_fill': 
'0.63328', 'stats_avg_J': '997.0450450450451 222'}
PID22632 2017-04-05 15:53:02,893 Info:Lattice Sieving: Found 740441 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.bcgg_86x.gz', total is 
now 1316800/800000
PID22632 2017-04-05 15:53:02,894 Info:Lattice Sieving: Marking workunit 
p50_sieving_4000-5000 as ok (164.6% => ETA Wed Apr  5 15:51:44 2017)
PID22632 2017-04-05 15:53:13,011 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:13,013 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:53:15,205 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:15,205 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:53:16,096 Debug:Lattice Sieving: stderr is: b"# 
Average J=1022 for 130 special-q's, max bucket fill 0.649841\n# Discarded 0 
special-q's out of 130 pushed\n# Total cpu time 113.36s [norm 0.08+1.5, 
sieving 35.7 (27.5 + 2.5 + 5.7), factor 76.0 (72.8 + 3.2)]\n# Total elapsed 
time 114.14s, per special-q 0.878009s, per relation 0.000132303s\n# 
PeakMemusage (MB) = 229 \n# Total 862723 reports [0.000131s/r, 6636.3r/sq]\n"
PID22632 2017-04-05 15:53:16,100 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_total_cpu_time': '113.36', 'stats_max_bucket_fill': '0.649841', 
'stats_avg_J': '1022.0 130'}
PID22632 2017-04-05 15:53:16,101 Debug:Lattice Sieving: Combined stats: 
{'stats_total_cpu_time': '297.09000000000003', 'stats_max_bucket_fill': 
'0.649841', 'stats_avg_J': '1006.2613636363636 352'}
PID22632 2017-04-05 15:53:16,101 Info:Lattice Sieving: Found 862723 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.4e4js5_h.gz', total is 
now 2179523/800000
PID22632 2017-04-05 15:53:16,102 Info:Lattice Sieving: Marking workunit 
p50_sieving_3000-4000 as ok (272.4% => ETA Wed Apr  5 15:51:28 2017)
PID22632 2017-04-05 15:53:17,022 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_8000-9000 to client localhost+4
PID22632 2017-04-05 15:53:17,022 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:17,043 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_9000-10000 to client localhost+5
PID22632 2017-04-05 15:53:17,044 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:17,210 Info:Lattice Sieving: Adding workunit 
p50_sieving_18000-19000 to database
PID22632 2017-04-05 15:53:17,242 Info:Lattice Sieving: Reached target of 
800000 relations, now have 2179523
PID22632 2017-04-05 15:53:17,246 Debug:Lattice Sieving: Exit 
SievingTask.run(sieving)
PID22632 2017-04-05 15:53:17,246 Info:Lattice Sieving: Aggregate statistics:
PID22632 2017-04-05 15:53:17,246 Info:Lattice Sieving: Total number of 
relations: 2179523
PID22632 2017-04-05 15:53:17,247 Info:Lattice Sieving: Average J: 1006.26 for 
352 special-q, max bucket fill: 0.649841
PID22632 2017-04-05 15:53:17,248 Info:Lattice Sieving: Total CPU time: 297.09s
PID22632 2017-04-05 15:53:17,249 Info:Filtering - Duplicate Removal, 
splitting pass: Starting
PID22632 2017-04-05 15:53:17,249 Debug:Filtering - Duplicate Removal, 
splitting pass: duplicates1.run(): Task state: {}
PID22632 2017-04-05 15:53:17,250 Debug:Filtering - Duplicate Removal, 
splitting pass: new files to split are: 
['/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.akf_u3sc.gz', 
'/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.bcgg_86x.gz', 
'/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.4e4js5_h.gz']
PID22632 2017-04-05 15:53:17,250 Info:Filtering - Duplicate Removal, 
splitting pass: Splitting 3 new files
PID22632 2017-04-05 15:53:17,299 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup1 -prefix dup1.0 -out 
/home/user/dlp/DLP50_1/p50.dup1/ -n 1 
/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.akf_u3sc.gz 
/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.bcgg_86x.gz 
/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.4e4js5_h.gz > 
/home/user/dlp/DLP50_1/p50.dup1.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup1.stderr.1
PID22632 2017-04-05 15:53:25,812 Debug:Command: Process with PID 22857 
finished successfully
PID22632 2017-04-05 15:53:25,828 Debug:Filtering - Duplicate Removal, 
splitting pass: Output file names: 
{'/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz': 0, 
'/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz': 1}
PID22632 2017-04-05 15:53:25,830 Debug:Filtering - Duplicate Removal, 
splitting pass: Newly arrived stats: {'stats_dup1_time': '8.4'}
PID22632 2017-04-05 15:53:25,830 Debug:Filtering - Duplicate Removal, 
splitting pass: Combined stats: {'stats_dup1_time': '8.4'}
PID22632 2017-04-05 15:53:25,864 Info:Filtering - Duplicate Removal, 
splitting pass: Relations per slice: 0: 1089979, 1: 1089544
PID22632 2017-04-05 15:53:25,866 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Duplicates1Task.run(duplicates1)
PID22632 2017-04-05 15:53:25,867 Info:Filtering - Duplicate Removal, 
splitting pass: Total cpu/real time for dup1: 8.01/8.515
PID22632 2017-04-05 15:53:25,867 Info:Filtering - Duplicate Removal, 
splitting pass: Aggregate statistics:
PID22632 2017-04-05 15:53:25,867 Info:Filtering - Duplicate Removal, 
splitting pass: CPU time for dup1: 8.4s
PID22632 2017-04-05 15:53:25,867 Info:Filtering - Duplicate Removal, removal 
pass: Starting
PID22632 2017-04-05 15:53:25,867 Debug:Filtering - Duplicate Removal, removal 
pass: duplicates2.run(): Task state: {}
PID22632 2017-04-05 15:53:25,925 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup2 -nrels 1089979 
-renumber /home/user/dlp/DLP50_1/p50.renumber.gz -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -dl 
/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz > 
/home/user/dlp/DLP50_1/p50.dup2.slice0.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup2.slice0.stderr.1
PID22632 2017-04-05 15:53:26,637 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:26,639 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID22632 2017-04-05 15:53:29,596 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_10000-11000 to client localhost+3
PID22632 2017-04-05 15:53:29,635 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID22632 2017-04-05 15:53:33,691 Debug:Command: Process with PID 22872 
finished successfully
PID22632 2017-04-05 15:53:33,760 Info:Filtering - Duplicate Removal, removal 
pass: 990817 unique relations remain on slice 0
PID22632 2017-04-05 15:53:33,876 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup2 -nrels 1089544 
-renumber /home/user/dlp/DLP50_1/p50.renumber.gz -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -dl 
/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz > 
/home/user/dlp/DLP50_1/p50.dup2.slice1.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup2.slice1.stderr.1
PID22632 2017-04-05 15:53:41,620 Debug:Command: Process with PID 22899 
finished successfully
PID22632 2017-04-05 15:53:41,747 Info:Filtering - Duplicate Removal, removal 
pass: 990763 unique relations remain on slice 1
PID22632 2017-04-05 15:53:41,756 Info:Filtering - Duplicate Removal, removal 
pass: Of 2179523 newly added relations 1981580 were unique (ratio 0.909181)
PID22632 2017-04-05 15:53:41,768 Info:Filtering - Duplicate Removal, removal 
pass: 1981580 unique relations remain in total
PID22632 2017-04-05 15:53:41,770 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Duplicates2Task.run(duplicates2)
PID22632 2017-04-05 15:53:41,770 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 32.12/15.5096
PID22632 2017-04-05 15:53:41,770 Info:Filtering - Singleton removal: Starting
PID22632 2017-04-05 15:53:41,770 Debug:Filtering - Singleton removal: 
purge.run(): Task state: {'input_nrels': 0}
PID22632 2017-04-05 15:53:41,790 Info:Filtering - Singleton removal: Reading 
1981580 unique and 12059 free relations, total 1993639
PID22632 2017-04-05 15:53:41,815 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/purge -out 
/home/user/dlp/DLP50_1/p50.purged.gz -nrels 1993639 -outdel 
/home/user/dlp/DLP50_1/p50.relsdel.gz -keep 3 -col-min-index 14780 
-col-max-index 591211 -t 11 -required_excess 0.0 
/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz 
/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz 
/home/user/dlp/DLP50_1/p50.freerel.gz > 
/home/user/dlp/DLP50_1/p50.purge.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.purge.stderr.1
PID22632 2017-04-05 15:54:16,416 Debug:Command: Process with PID 22926 
finished successfully
PID22632 2017-04-05 15:54:16,474 Info:Filtering - Singleton removal: After 
purge, 14781 relations with 14778 primes remain with weight 308303 and excess 
3
PID22632 2017-04-05 15:54:16,485 Info:Filtering - Singleton removal: Have 
enough relations
PID22632 2017-04-05 15:54:16,486 Info:HTTP server: Got notification to stop 
serving Workunits
PID22632 2017-04-05 15:54:16,486 Info:Lattice Sieving: Cancelling remaining 
workunits
PID22632 2017-04-05 15:54:16,541 Command:Command: /bin/kill 22652
PID22632 2017-04-05 15:54:16,543 Debug:Command: Process with PID 25344 
finished successfully
PID22632 2017-04-05 15:54:16,544 Info:Client Launcher: Stopped client 
localhost+5 (Host localhost, PID 22652)
PID22632 2017-04-05 15:54:16,556 Command:Command: /bin/kill 22654
PID22632 2017-04-05 15:54:16,559 Debug:Command: Process with PID 25345 
finished successfully
PID22632 2017-04-05 15:54:16,559 Info:Client Launcher: Stopped client 
localhost+6 (Host localhost, PID 22654)
PID22632 2017-04-05 15:54:16,586 Command:Command: /bin/kill 22642
PID22632 2017-04-05 15:54:16,588 Debug:Command: Process with PID 25346 
finished successfully
PID22632 2017-04-05 15:54:16,588 Info:Client Launcher: Stopped client 
localhost+4 (Host localhost, PID 22642)
PID22632 2017-04-05 15:54:16,608 Command:Command: /bin/kill 22640
PID22632 2017-04-05 15:54:16,609 Debug:Command: Process with PID 25347 
finished successfully
PID22632 2017-04-05 15:54:16,609 Info:Client Launcher: Stopped client 
localhost+3 (Host localhost, PID 22640)
PID22632 2017-04-05 15:54:16,615 Command:Command: /bin/kill 22638
PID22632 2017-04-05 15:54:16,615 Debug:Command: Process with PID 25348 
finished successfully
PID22632 2017-04-05 15:54:16,615 Info:Client Launcher: Stopped client 
localhost+2 (Host localhost, PID 22638)
PID22632 2017-04-05 15:54:16,623 Command:Command: /bin/kill 22636
PID22632 2017-04-05 15:54:16,624 Debug:Command: Process with PID 25349 
finished successfully
PID22632 2017-04-05 15:54:16,624 Info:Client Launcher: Stopped client 
localhost (Host localhost, PID 22636)
PID22632 2017-04-05 15:54:16,628 Debug:Filtering - Singleton removal: Exit 
PurgeTask.run(purge)
PID22632 2017-04-05 15:54:16,628 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 85.69/34.6012
PID22632 2017-04-05 15:54:16,628 Info:Filtering - Merging: Starting
PID22632 2017-04-05 15:54:16,628 Debug:Filtering - Merging: mergedlp.run(): 
Task state: {}
PID22632 2017-04-05 15:54:16,636 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/merge-dl -mat 
/home/user/dlp/DLP50_1/p50.purged.gz -out 
/home/user/dlp/DLP50_1/p50.history.gz -maxlevel 15 -keep 3 -skip 0 
-target_density 100.0 -t 11 > /home/user/dlp/DLP50_1/p50.merge-dl.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.merge-dl.stderr.1
PID22632 2017-04-05 15:54:17,301 Debug:Command: Process with PID 25350 
finished successfully
PID22632 2017-04-05 15:54:17,305 Info:Filtering - Merging: Merged matrix has 
2687 rows and total weight 255692 (95.2 entries per row on average)
PID22632 2017-04-05 15:54:17,309 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/replay-dl -purged 
/home/user/dlp/DLP50_1/p50.purged.gz -ideals /home/user/dlp/DLP50_1/p50.ideal 
-his /home/user/dlp/DLP50_1/p50.history.gz -index 
/home/user/dlp/DLP50_1/p50.index.gz -out 
/home/user/dlp/DLP50_1/p50.sparse.bin > 
/home/user/dlp/DLP50_1/p50.replay.stdout.2 2> 
/home/user/dlp/DLP50_1/p50.replay.stderr.2
PID22632 2017-04-05 15:54:17,468 Debug:Command: Process with PID 25373 
finished successfully
PID22632 2017-04-05 15:54:17,474 Debug:Filtering - Merging: Exit 
MergeDLPTask.run(mergedlp)
PID22632 2017-04-05 15:54:17,474 Info:Filtering - Merging: Total cpu/real 
time for merge-dl: 0.58/0.66476
PID22632 2017-04-05 15:54:17,474 Info:Filtering - Merging: Total cpu/real 
time for replay-dl: 0.14/0.158302
PID22632 2017-04-05 15:54:17,475 Info:Schirokauer Maps: Starting
PID22632 2017-04-05 15:54:17,475 Debug:Schirokauer Maps: sm.run(): Task 
state: {}
PID22632 2017-04-05 15:54:17,482 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/sm -poly 
/home/user/dlp/DLP50_1/p50.poly -purged /home/user/dlp/DLP50_1/p50.purged.gz 
-index /home/user/dlp/DLP50_1/p50.index.gz -out /home/user/dlp/DLP50_1/p50.sm 
-ell 15707963267948966192313216916397514420985847001161 -nsm '0,3' -t 11 > 
/home/user/dlp/DLP50_1/p50.sm.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.sm.stderr.1
PID22632 2017-04-05 15:54:21,549 Debug:Command: Process with PID 25384 
finished successfully
PID22632 2017-04-05 15:54:21,554 Debug:Schirokauer Maps: Exit SMTask.run(sm)
PID22632 2017-04-05 15:54:21,554 Info:Schirokauer Maps: Total cpu/real time 
for sm: 36.42/4.06709
PID22632 2017-04-05 15:54:21,555 Info:Linear Algebra: Starting
PID22632 2017-04-05 15:54:21,555 Debug:Linear Algebra: linalgdlp.run(): Task 
state: {'ran_already': False}
PID22632 2017-04-05 15:54:21,562 Info:Linear Algebra: Using 3 as default 
value for n to account for Schirokauer maps
PID22632 2017-04-05 15:54:21,562 Info:Linear Algebra: Using 2*n=6 as default 
value for m
PID22632 2017-04-05 15:54:21,564 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl :complete 
'thr=11' 'm=6' 'n=3' 'nullspace=right' 'interval=1000' 
'matrix=/home/user/dlp/DLP50_1/p50.sparse.bin' 
'rhs=/home/user/dlp/DLP50_1/p50.sm' 
'prime=15707963267948966192313216916397514420985847001161' 
'wdir=/home/user/dlp/DLP50_1/p50.bwc' 'cpubinding=false' 
'cantor_threshold=2048' > /home/user/dlp/DLP50_1/p50.bwc.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.bwc.stderr.1
PID22632 2017-04-05 15:55:59,303 Warning:Command: Process with PID 25410 
finished with return code 2
PID22632 2017-04-05 15:55:59,314 Error:Linear Algebra: Program run on server 
failed with exit code 2
PID22632 2017-04-05 15:55:59,314 Error:Linear Algebra: Command line was: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl :complete 
'thr=11' 'm=6' 'n=3' 'nullspace=right' 'interval=1000' 
'matrix=/home/user/dlp/DLP50_1/p50.sparse.bin' 
'rhs=/home/user/dlp/DLP50_1/p50.sm' 
'prime=15707963267948966192313216916397514420985847001161' 
'wdir=/home/user/dlp/DLP50_1/p50.bwc' 'cpubinding=false' 
'cantor_threshold=2048' > /home/user/dlp/DLP50_1/p50.bwc.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.bwc.stderr.1
PID22632 2017-04-05 15:55:59,314 Error:Linear Algebra: Stderr output follows 
(stored in file /home/user/dlp/DLP50_1/p50.bwc.stderr.1):
b'readlink(/usr/local/bin//mpiexec)->mpiexec.hydra\nAuto-detecting mpich or 
mvapich2 (hydra) based on alternatives\nUsing mpich-3.2, 
MPI_BINDIR=/usr/local/bin/\nUse of uninitialized value in split at 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl line 921, <F> 
line 
1.\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 mkdir -p 
/home/user/dlp/DLP50_1/p50.bwc\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/prep 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 
rhs=/home/user/dlp/DLP50_1/p50.sm thr=1x11 m=6 
matrix=/home/user/dlp/DLP50_1/p50.sparse.bin interval=1000 mpi=1x1 
cpubinding=false 
prime=15707963267948966192313216916397514420985847001161\nWriting balancing 
data to 
p50.sparse.1x11.bin\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/secure 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 thr=1x11 m=6 
matrix=/home/user/dlp/DLP50_1/p50.sparse.bin interval=1000 mpi=1x1 
cpubinding=false 
prime=15707963267948966192313216916397514420985847001161\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/krylov 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 thr=1x11 m=6 
matrix=/home/user/dlp/DLP50_1/p50.sparse.bin interval=1000 mpi=1x1 
cpubinding=false prime=15707963267948966192313216916397514420985847001161 
ys=0..1 start=0\nTarget iteration is 1360 ; going to 
2000\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/krylov 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 thr=1x11 m=6 
matrix=/home/user/dlp/DLP50_1/p50.sparse.bin interval=1000 mpi=1x1 
cpubinding=false prime=15707963267948966192313216916397514420985847001161 
ys=1..2 start=0\nTarget iteration is 1360 ; going to 
2000\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/krylov 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 thr=1x11 m=6 
matrix=/home/user/dlp/DLP50_1/p50.sparse.bin interval=1000 mpi=1x1 
cpubinding=false prime=15707963267948966192313216916397514420985847001161 
ys=2..3 start=0\nTarget iteration is 1360 ; going to 
2000\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/acollect 
nullspace=right wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 m=6 interval=1000 
prime=15707963267948966192313216916397514420985847001161 
--remove-old\n#############################################################################\n/usr/local/bin//mpiexec
 -n 1 /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/plingen_pz 
lingen_threshold=10 lingen_mpi_threshold=10 split-output-file=1 
afile=A0-3.0-2000 ffile=F wdir=/home/user/dlp/DLP50_1/p50.bwc n=3 
rhs=/home/user/dlp/DLP50_1/p50.sm m=6 
prime=15707963267948966192313216916397514420985847001161 mpi=1x1\nERROR: all 
solutions have zero contribution on the RHS vectors -- we will just output 
right kernel vectors (maybe use allow_zero_on_rhs 
?)\n/usr/local/bin//mpiexec: exited with status 1\naborted on subprogram 
error at /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl line 
508, <F> line 1.\n\t...propagated at 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl line 1387, <F> 
line 1.\n'
PID25654 2017-04-05 16:03:49,239 Info:root: Command line parameters: 
./cado-nfs.py -dlp -ell 15707963267948966192313216916397514420985847001161 
target=24509473578884360875565352586814397447467113190347 
31415926535897932384626433832795028841971694002323 server.ssl=no -w 
/home/user/dlp/DLP50_1
PID25654 2017-04-05 16:03:49,239 Debug:root: Root parameter dictionary:
N = 31415926535897932384626433832795028841971694002323
descent_hint = /home/user/cado/cado_mpi/share/cado-nfs-2.2.1/dlp/p50.hint
dlp = True
ell = 15707963267948966192313216916397514420985847001161
gfpext = 1
lim0 = 600000
lim1 = 600000
lpb0 = 22
lpb1 = 22
name = p50
target = 24509473578884360875565352586814397447467113190347
server.ssl = no
slaves.basepath = /home/user/dlp/DLP50_1/client
slaves.hostnames = localhost
slaves.nrclients = 6
slaves.scriptpath = /home/user/cado/cado_mpi/bin
tasks.I = 11
tasks.execpath = /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1
tasks.threads = 11
tasks.workdir = /home/user/dlp/DLP50_1
tasks.descent.I = 11
tasks.descent.init_I = 11
tasks.descent.init_lim = 100000
tasks.descent.init_lpb = 24
tasks.descent.init_mfb = 48
tasks.descent.init_ncurves = 10
tasks.descent.init_tkewness = 200000
tasks.descent.lim0 = 100000
tasks.descent.lim1 = 100000
tasks.descent.lpb0 = 22
tasks.descent.lpb1 = 22
tasks.descent.mfb0 = 44
tasks.descent.mfb1 = 44
tasks.filter.maxlevel = 15
tasks.filter.target_density = 100
tasks.linalg.allow_zero_on_rhs = 1
tasks.linalg.bwc.cpubinding = false
tasks.linalg.bwc.interval = 1000
tasks.polyselect.P = 420
tasks.polyselect.admax = 1000
tasks.polyselect.adrange = 500
tasks.polyselect.degree = 4
tasks.polyselect.incr = 60
tasks.polyselect.nq = 1000
tasks.polyselect.threads = 2
tasks.reconstructlog.partial = false
tasks.sieve.lambda0 = 1.2
tasks.sieve.lambda1 = 1.2
tasks.sieve.mfb0 = 24
tasks.sieve.mfb1 = 24
tasks.sieve.qmin = 35
tasks.sieve.qrange = 1000
tasks.sieve.rels_wanted = 800000
tasks.sieve.las.threads = 2
tasks.sqrt.threads = 8
PID25654 2017-04-05 16:03:49,240 Debug:root: Writing parameter snapshot to 
/home/user/dlp/DLP50_1/p50.parameters_snapshot.0
PID25654 2017-04-05 16:03:49,241 Info:root: If this computation gets 
interrupted, it can be resumed with ./cado-nfs.py 
/home/user/dlp/DLP50_1/p50.parameters_snapshot.0
PID25654 2017-04-05 16:03:49,324 Debug:Client Launcher: Enter 
Task.__init__(slaves)
PID25654 2017-04-05 16:03:49,324 Debug:Client Launcher: state = {}
PID25654 2017-04-05 16:03:49,324 Debug:Client Launcher: self.parameters = 
MyParameters: name = None, prefix = ['slaves']
PID25654 2017-04-05 16:03:49,324 Debug:Client Launcher: params = 
{'scriptpath': '/home/user/cado/cado_mpi/bin', 'hostnames': 'localhost', 
'run': True, 'nrclients': 6}
PID25654 2017-04-05 16:03:49,325 Debug:Client Launcher: 
slaves.cado_nfs_client.basepath found from slaves.basepath
PID25654 2017-04-05 16:03:49,325 Debug:Client Launcher: Exit 
Task.__init__(slaves)
PID25654 2017-04-05 16:03:49,350 Info:Server Launcher: Adding 192.168.1.97 to 
whitelist to allow clients on localhost to connect
PID25654 2017-04-05 16:03:49,359 Debug:HTTP server: Found upload.py at 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:03:49,363 Info:HTTP server: Using non-threaded HTTP 
server
PID25654 2017-04-05 16:03:49,364 Info:HTTP server: Using whitelist: 
localhost,192.168.1.97
PID25654 2017-04-05 16:03:49,379 Debug:Generate Factor Base: Enter 
Task.__init__(factorbase)
PID25654 2017-04-05 16:03:49,380 Debug:Generate Factor Base: state = {}
PID25654 2017-04-05 16:03:49,380 Debug:Generate Factor Base: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'sieve']
PID25654 2017-04-05 16:03:49,380 Debug:Generate Factor Base: params = 
{'gzip': True, 'I': 11, 'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 
'lim0': 600000, 'run': True, 'lim1': 600000}
PID25654 2017-04-05 16:03:49,380 Debug:Generate Factor Base: 
tasks.sieve.makefb.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,380 Debug:Generate Factor Base: 
tasks.sieve.makefb.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,381 Debug:Generate Factor Base: Exit 
Task.__init__(factorbase)
PID25654 2017-04-05 16:03:49,391 Debug:Generate Free Relations: Enter 
Task.__init__(freerel)
PID25654 2017-04-05 16:03:49,391 Debug:Generate Free Relations: state = {}
PID25654 2017-04-05 16:03:49,391 Debug:Generate Free Relations: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'sieve']
PID25654 2017-04-05 16:03:49,391 Debug:Generate Free Relations: params = 
{'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 'run': True, 'gzip': 
True, 'dlp': True}
PID25654 2017-04-05 16:03:49,392 Debug:Generate Free Relations: 
tasks.sieve.freerel.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,392 Debug:Generate Free Relations: 
tasks.sieve.freerel.lpb0 found from lpb0
PID25654 2017-04-05 16:03:49,392 Debug:Generate Free Relations: 
tasks.sieve.freerel.lpb1 found from lpb1
PID25654 2017-04-05 16:03:49,392 Debug:Generate Free Relations: 
tasks.sieve.freerel.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,392 Debug:Generate Free Relations: Exit 
Task.__init__(freerel)
PID25654 2017-04-05 16:03:49,409 Debug:Lattice Sieving: Enter 
Task.__init__(sieving)
PID25654 2017-04-05 16:03:49,409 Debug:Lattice Sieving: state = {}
PID25654 2017-04-05 16:03:49,409 Debug:Lattice Sieving: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID25654 2017-04-05 16:03:49,409 Debug:Lattice Sieving: params = 
{'maxtimedout': 100, 'run': True, 'qrange': 1000, 'qmin': 35, 'lim1': 600000, 
'gzip': True, 'rels_wanted': 800000, 'maxwu': 10, 'maxresubmit': 5, 'name': 
'p50', 'workdir': '/home/user/dlp/DLP50_1', 'maxfailed': 100, 'wutimeout': 
10800}
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.lim1 
found from lim1
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.I 
found from tasks.I
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.lpb1 
found from lpb1
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.mfb0 
found from tasks.sieve.mfb0
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: 
tasks.sieve.las.lambda1 found from tasks.sieve.lambda1
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.mfb1 
found from tasks.sieve.mfb1
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: 
tasks.sieve.las.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.lpb0 
found from lpb0
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: 
tasks.sieve.las.lambda0 found from tasks.sieve.lambda0
PID25654 2017-04-05 16:03:49,410 Debug:Lattice Sieving: tasks.sieve.las.lim0 
found from lim0
PID25654 2017-04-05 16:03:49,411 Debug:Lattice Sieving: 
tasks.sieve.las.threads found from tasks.sieve.las.threads
PID25654 2017-04-05 16:03:49,411 Debug:Lattice Sieving: Exit 
Task.__init__(sieving)
PID25654 2017-04-05 16:03:49,458 Debug:Filtering - Duplicate Removal, 
splitting pass: Enter Task.__init__(duplicates1)
PID25654 2017-04-05 16:03:49,458 Debug:Filtering - Duplicate Removal, 
splitting pass: state = {}
PID25654 2017-04-05 16:03:49,458 Debug:Filtering - Duplicate Removal, 
splitting pass: self.parameters = MyParameters: name = None, prefix = 
['tasks', 'filter']
PID25654 2017-04-05 16:03:49,458 Debug:Filtering - Duplicate Removal, 
splitting pass: params = {'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 
'nslices_log': 1, 'run': True}
PID25654 2017-04-05 16:03:49,459 Debug:Filtering - Duplicate Removal, 
splitting pass: tasks.filter.dup1.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,459 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Task.__init__(duplicates1)
PID25654 2017-04-05 16:03:49,496 Debug:Filtering - Duplicate Removal, removal 
pass: Enter Task.__init__(duplicates2)
PID25654 2017-04-05 16:03:49,496 Debug:Filtering - Duplicate Removal, removal 
pass: state = {}
PID25654 2017-04-05 16:03:49,496 Debug:Filtering - Duplicate Removal, removal 
pass: self.parameters = MyParameters: name = None, prefix = ['tasks', 
'filter']
PID25654 2017-04-05 16:03:49,496 Debug:Filtering - Duplicate Removal, removal 
pass: params = {'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 
'nslices_log': 1, 'run': True, 'dlp': True}
PID25654 2017-04-05 16:03:49,496 Debug:Filtering - Duplicate Removal, removal 
pass: tasks.filter.dup2.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,497 Debug:Filtering - Duplicate Removal, removal 
pass: tasks.filter.dup2.dlp found from dlp
PID25654 2017-04-05 16:03:49,497 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Task.__init__(duplicates2)
PID25654 2017-04-05 16:03:49,528 Debug:Filtering - Singleton removal: Enter 
Task.__init__(purge)
PID25654 2017-04-05 16:03:49,529 Debug:Filtering - Singleton removal: state = 
{}
PID25654 2017-04-05 16:03:49,529 Debug:Filtering - Singleton removal: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'filter']
PID25654 2017-04-05 16:03:49,529 Debug:Filtering - Singleton removal: params 
= {'required_excess': 0.0, 'run': True, 'gzip': True, 'galois': 'none', 
'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 'dlp': True, 'add_ratio': 
0.01}
PID25654 2017-04-05 16:03:49,530 Debug:Filtering - Singleton removal: 
tasks.filter.purge.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,530 Debug:Filtering - Singleton removal: 
tasks.filter.purge.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,530 Debug:Filtering - Singleton removal: Exit 
Task.__init__(purge)
PID25654 2017-04-05 16:03:49,571 Debug:Polynomial Selection (size optimized): 
Enter Task.__init__(polyselect1)
PID25654 2017-04-05 16:03:49,571 Debug:Polynomial Selection (size optimized): 
state = {}
PID25654 2017-04-05 16:03:49,572 Debug:Polynomial Selection (size optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID25654 2017-04-05 16:03:49,572 Debug:Polynomial Selection (size optimized): 
params = {'maxtimedout': 100, 'run': True, 'nrkeep': 20, 'adrange': 500, 
'maxwu': 10, 'workdir': '/home/user/dlp/DLP50_1', 'name': 'p50', 'N': 
31415926535897932384626433832795028841971694002323, 'admax': 1000, 'admin': 
0, 'maxfailed': 100, 'wutimeout': 10800, 'maxresubmit': 5}
PID25654 2017-04-05 16:03:49,572 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.P found from tasks.polyselect.P
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.degree found from tasks.polyselect.degree
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.N found from N
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.admax found from tasks.polyselect.admax
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.threads found from tasks.polyselect.threads
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,573 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.incr found from tasks.polyselect.incr
PID25654 2017-04-05 16:03:49,574 Debug:Polynomial Selection (size optimized): 
tasks.polyselect.polyselect.nq found from tasks.polyselect.nq
PID25654 2017-04-05 16:03:49,574 Debug:Polynomial Selection (size optimized): 
Exit Task.__init__(polyselect1)
PID25654 2017-04-05 16:03:49,623 Debug:Polynomial Selection (root optimized): 
Enter Task.__init__(polyselect2)
PID25654 2017-04-05 16:03:49,623 Debug:Polynomial Selection (root optimized): 
state = {}
PID25654 2017-04-05 16:03:49,624 Debug:Polynomial Selection (root optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID25654 2017-04-05 16:03:49,624 Debug:Polynomial Selection (root optimized): 
params = {'maxtimedout': 100, 'run': True, 'lim0': 600000, 'workdir': 
'/home/user/dlp/DLP50_1', 'I': 11, 'maxwu': 10, 'maxresubmit': 5, 'name': 
'p50', 'N': 31415926535897932384626433832795028841971694002323, 'lim1': 
600000, 'maxfailed': 100, 'wutimeout': 10800}
PID25654 2017-04-05 16:03:49,625 Debug:Polynomial Selection (root optimized): 
tasks.polyselect.polyselect_ropt.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,625 Debug:Polynomial Selection (root optimized): 
tasks.polyselect.polyselect_ropt.threads found from tasks.polyselect.threads
PID25654 2017-04-05 16:03:49,625 Debug:Polynomial Selection (root optimized): 
Exit Task.__init__(polyselect2)
PID25654 2017-04-05 16:03:49,667 Debug:Number Theory for DLP: Enter 
Task.__init__(numbertheory)
PID25654 2017-04-05 16:03:49,667 Debug:Number Theory for DLP: state = {}
PID25654 2017-04-05 16:03:49,667 Debug:Number Theory for DLP: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'numbertheory']
PID25654 2017-04-05 16:03:49,667 Debug:Number Theory for DLP: params = 
{'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 'nsm1': -1, 'run': True, 
'nsm0': -1}
PID25654 2017-04-05 16:03:49,668 Debug:Number Theory for DLP: 
tasks.numbertheory.badideals.ell found from ell
PID25654 2017-04-05 16:03:49,668 Debug:Number Theory for DLP: 
tasks.numbertheory.badideals.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,668 Debug:Number Theory for DLP: Exit 
Task.__init__(numbertheory)
PID25654 2017-04-05 16:03:49,682 Debug:Filtering - Galois: Enter 
Task.__init__(filtergalois)
PID25654 2017-04-05 16:03:49,682 Debug:Filtering - Galois: state = {}
PID25654 2017-04-05 16:03:49,682 Debug:Filtering - Galois: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID25654 2017-04-05 16:03:49,683 Debug:Filtering - Galois: params = {'name': 
'p50', 'workdir': '/home/user/dlp/DLP50_1', 'run': True, 'galois': 'none'}
PID25654 2017-04-05 16:03:49,683 Debug:Filtering - Galois: 
tasks.filter.filter_galois.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,683 Debug:Filtering - Galois: Exit 
Task.__init__(filtergalois)
PID25654 2017-04-05 16:03:49,696 Debug:Schirokauer Maps: Enter 
Task.__init__(sm)
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: state = {}
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: params = {'name': 
'p50', 'workdir': '/home/user/dlp/DLP50_1', 'run': True}
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: tasks.filter.sm.ell 
found from ell
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: 
tasks.filter.sm.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,697 Debug:Schirokauer Maps: 
tasks.filter.sm.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,698 Debug:Schirokauer Maps: Exit 
Task.__init__(sm)
PID25654 2017-04-05 16:03:49,706 Debug:Filtering - Merging: Enter 
Task.__init__(mergedlp)
PID25654 2017-04-05 16:03:49,706 Debug:Filtering - Merging: state = {}
PID25654 2017-04-05 16:03:49,706 Debug:Filtering - Merging: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID25654 2017-04-05 16:03:49,706 Debug:Filtering - Merging: params = {'name': 
'p50', 'workdir': '/home/user/dlp/DLP50_1', 'run': True, 'gzip': True}
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: 
tasks.filter.merge-dl.target_density found from tasks.filter.target_density
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: 
tasks.filter.merge-dl.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: 
tasks.filter.merge-dl.maxlevel found from tasks.filter.maxlevel
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: 
tasks.filter.merge-dl.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: 
tasks.filter.replay-dl.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,707 Debug:Filtering - Merging: Exit 
Task.__init__(mergedlp)
PID25654 2017-04-05 16:03:49,717 Debug:Linear Algebra: Enter 
Task.__init__(linalgdlp)
PID25654 2017-04-05 16:03:49,717 Debug:Linear Algebra: state = {}
PID25654 2017-04-05 16:03:49,718 Debug:Linear Algebra: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'linalg']
PID25654 2017-04-05 16:03:49,718 Debug:Linear Algebra: params = 
{'force_wipeout': False, 'n': 0, 'm': 0, 'ell': 
15707963267948966192313216916397514420985847001161, 'workdir': 
'/home/user/dlp/DLP50_1', 'run': True, 'name': 'p50'}
PID25654 2017-04-05 16:03:49,718 Debug:Linear Algebra: 
tasks.linalg.bwc.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,718 Debug:Linear Algebra: 
tasks.linalg.bwc.allow_zero_on_rhs found from tasks.linalg.allow_zero_on_rhs
PID25654 2017-04-05 16:03:49,718 Debug:Linear Algebra: 
tasks.linalg.bwc.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,719 Debug:Linear Algebra: 
tasks.linalg.bwc.interval found from tasks.linalg.bwc.interval
PID25654 2017-04-05 16:03:49,719 Debug:Linear Algebra: 
tasks.linalg.bwc.cpubinding found from tasks.linalg.bwc.cpubinding
PID25654 2017-04-05 16:03:49,719 Debug:Linear Algebra: Exit 
Task.__init__(linalgdlp)
PID25654 2017-04-05 16:03:49,731 Debug:Logarithms Reconstruction: Enter 
Task.__init__(reconstructlog)
PID25654 2017-04-05 16:03:49,731 Debug:Logarithms Reconstruction: state = {}
PID25654 2017-04-05 16:03:49,731 Debug:Logarithms Reconstruction: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 
'reconstructlog']
PID25654 2017-04-05 16:03:49,731 Debug:Logarithms Reconstruction: params = 
{'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 'run': True, 'checkdlp': 
True}
PID25654 2017-04-05 16:03:49,732 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.ell found from ell
PID25654 2017-04-05 16:03:49,732 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,732 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.threads found from tasks.threads
PID25654 2017-04-05 16:03:49,732 Debug:Logarithms Reconstruction: 
tasks.reconstructlog.reconstructlog-dl.partial found from 
tasks.reconstructlog.partial
PID25654 2017-04-05 16:03:49,732 Debug:Logarithms Reconstruction: Exit 
Task.__init__(reconstructlog)
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: Enter 
Task.__init__(descent)
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: state = {}
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: self.parameters 
= MyParameters: name = None, prefix = ['tasks', 'descent']
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: params = 
{'name': 'p50', 'workdir': '/home/user/dlp/DLP50_1', 'execpath': 
'/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1', 'run': True, 'target': 
24509473578884360875565352586814397447467113190347}
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: 
tasks.descent.descent.init_tkewness found from tasks.descent.init_tkewness
PID25654 2017-04-05 16:03:49,741 Debug:Individual logarithm: 
tasks.descent.descent.lpb0 found from tasks.descent.lpb0
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.target found from target
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.mfb0 found from tasks.descent.mfb0
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.init_ncurves found from tasks.descent.init_ncurves
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.mfb1 found from tasks.descent.mfb1
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.execpath found from tasks.execpath
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.init_mfb found from tasks.descent.init_mfb
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.lim1 found from tasks.descent.lim1
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.I found from tasks.descent.I
PID25654 2017-04-05 16:03:49,742 Debug:Individual logarithm: 
tasks.descent.descent.descent_hint found from descent_hint
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.init_I found from tasks.descent.init_I
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.init_lim found from tasks.descent.init_lim
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.ell found from ell
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.lpb1 found from tasks.descent.lpb1
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.lim0 found from tasks.descent.lim0
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: 
tasks.descent.descent.init_lpb found from tasks.descent.init_lpb
PID25654 2017-04-05 16:03:49,743 Debug:Individual logarithm: Exit 
Task.__init__(descent)
PID25654 2017-04-05 16:03:49,746 Warning:Complete Factorization: Parameter 
tasks.sqrt.threads = 8 was not used anywhere
PID25654 2017-04-05 16:03:49,746 Warning:Complete Factorization: Perhaps you 
meant one of the following ?
PID25654 2017-04-05 16:03:49,746 Warning:Complete Factorization:   
tasks.polyselect.polyselect.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.polyselect.polyselect_ropt.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.sieve.makefb.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.sieve.freerel.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.sieve.las.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.filter.purge.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.filter.merge-dl.threads ?
PID25654 2017-04-05 16:03:49,747 Warning:Complete Factorization:   
tasks.filter.sm.threads ?
PID25654 2017-04-05 16:03:49,748 Warning:Complete Factorization:   
tasks.linalg.bwc.threads ?
PID25654 2017-04-05 16:03:49,748 Warning:Complete Factorization:   
tasks.reconstructlog.reconstructlog-dl.threads ?
PID25654 2017-04-05 16:03:49,748 Warning:Complete Factorization: (If you wish 
to set all of these consistently, you may set tasks.threads)
PID25654 2017-04-05 16:03:49,748 Info:Complete Factorization: Computing 
Discrete Logs in GF(31415926535897932384626433832795028841971694002323)
PID25654 2017-04-05 16:03:49,754 Info:HTTP server: serving at 
http://192.168.1.97:40198 (0.0.0.0)
PID25654 2017-04-05 16:03:49,754 Info:HTTP server: For debugging purposes, 
the URL above can be accessed if the server.only_registered=False parameter 
is added
PID25654 2017-04-05 16:03:49,754 Info:HTTP server: You can start additional 
cado-nfs-client.py scripts with parameters: --server=http://192.168.1.97:40198
PID25654 2017-04-05 16:03:49,754 Info:HTTP server: If you want to start 
additional clients, remember to add their hosts to server.whitelist
PID25654 2017-04-05 16:03:49,755 Info:Client Launcher: Starting client id 
localhost on host localhost
PID25654 2017-04-05 16:03:49,759 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:49,836 Debug:Command: Process with PID 25657 
finished successfully
PID25654 2017-04-05 16:03:49,836 Debug:Command: Process with PID 25657 
stdout: b'PID: 25658\n'
PID25654 2017-04-05 16:03:49,836 Debug:Command: Process with PID 25657 
stderr: b'INFO:root:Starting client localhost\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:49,842 Info:Client Launcher: Starting client id 
localhost+2 on host localhost
PID25654 2017-04-05 16:03:49,845 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost+2 --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:49,914 Debug:Command: Process with PID 25659 
finished successfully
PID25654 2017-04-05 16:03:49,914 Debug:Command: Process with PID 25659 
stdout: b'PID: 25660\n'
PID25654 2017-04-05 16:03:49,914 Debug:Command: Process with PID 25659 
stderr: b'INFO:root:Starting client localhost+2\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:49,923 Info:Client Launcher: Starting client id 
localhost+3 on host localhost
PID25654 2017-04-05 16:03:49,926 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost+3 --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:49,993 Debug:Command: Process with PID 25661 
finished successfully
PID25654 2017-04-05 16:03:49,993 Debug:Command: Process with PID 25661 
stdout: b'PID: 25662\n'
PID25654 2017-04-05 16:03:49,993 Debug:Command: Process with PID 25661 
stderr: b'INFO:root:Starting client localhost+3\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:50,002 Info:Client Launcher: Starting client id 
localhost+4 on host localhost
PID25654 2017-04-05 16:03:50,005 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost+4 --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:50,073 Debug:Command: Process with PID 25663 
finished successfully
PID25654 2017-04-05 16:03:50,073 Debug:Command: Process with PID 25663 
stdout: b'PID: 25664\n'
PID25654 2017-04-05 16:03:50,074 Debug:Command: Process with PID 25663 
stderr: b'INFO:root:Starting client localhost+4\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:50,080 Info:Client Launcher: Starting client id 
localhost+5 on host localhost
PID25654 2017-04-05 16:03:50,082 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost+5 --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:50,150 Debug:Command: Process with PID 25665 
finished successfully
PID25654 2017-04-05 16:03:50,150 Debug:Command: Process with PID 25665 
stdout: b'PID: 25666\n'
PID25654 2017-04-05 16:03:50,150 Debug:Command: Process with PID 25665 
stderr: b'INFO:root:Starting client localhost+5\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:50,168 Info:Client Launcher: Starting client id 
localhost+6 on host localhost
PID25654 2017-04-05 16:03:50,171 Command:Command: 
/home/user/cado/cado_mpi/bin/cado-nfs-client.py --server 
http://localhost:40198 --daemon --clientid localhost+6 --basepath 
/home/user/dlp/DLP50_1/client
PID25654 2017-04-05 16:03:50,237 Debug:Command: Process with PID 25675 
finished successfully
PID25654 2017-04-05 16:03:50,237 Debug:Command: Process with PID 25675 
stdout: b'PID: 25676\n'
PID25654 2017-04-05 16:03:50,237 Debug:Command: Process with PID 25675 
stderr: b'INFO:root:Starting client localhost+6\nINFO:root:Python version is 
2.7.5\n'
PID25654 2017-04-05 16:03:50,242 Info:Client Launcher: Running clients: 
localhost+2 (Host localhost, PID 25660), localhost+3 (Host localhost, PID 
25662), localhost+5 (Host localhost, PID 25666), localhost+6 (Host localhost, 
PID 25676), localhost (Host localhost, PID 25658), localhost+4 (Host 
localhost, PID 25664)
PID25654 2017-04-05 16:03:50,243 Info:Polynomial Selection (size optimized): 
Starting
PID25654 2017-04-05 16:03:50,243 Debug:Polynomial Selection (size optimized): 
polyselect1.run(): Task state: {'wu_failed': 0, 'start_real_time': 0, 
'wu_submitted': 0, 'wu_timedout': 0, 'wu_received': 0, 'adnext': 0, 
'start_achievement': -1}
PID25654 2017-04-05 16:03:50,243 Info:Polynomial Selection (size optimized): 
0 polynomials in queue from previous run
PID25654 2017-04-05 16:03:50,245 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID25654 2017-04-05 16:03:50,248 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect is 
('c5c4c6fece0d48dcc30878ee38fd0fbbee5a428f', (212431668, 935665, 
1490019316.358416))
PID25654 2017-04-05 16:03:50,248 Debug:Server Launcher: Registering file name 
polyselect with target 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID25654 2017-04-05 16:03:50,253 Info:Polynomial Selection (size optimized): 
Adding workunit p50_polyselect1_0-500 to database
PID25654 2017-04-05 16:03:50,264 Info:Polynomial Selection (size optimized): 
Adding workunit p50_polyselect1_500-1000 to database
PID25654 2017-04-05 16:03:50,272 Debug:Polynomial Selection (size optimized): 
Setting last timeout check to 1491397430.272416
PID25654 2017-04-05 16:03:59,868 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect1_0-500 to client localhost
PID25654 2017-04-05 16:03:59,868 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID25654 2017-04-05 16:03:59,872 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect
PID25654 2017-04-05 16:03:59,872 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:03:59,946 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect1_500-1000 to client localhost+2
PID25654 2017-04-05 16:03:59,947 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:00,339 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:00,339 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:00,628 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:00,628 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:01,433 Info:Polynomial Selection (size optimized): 
Parsed 37 polynomials, added 28 to priority queue (has 20)
PID25654 2017-04-05 16:04:01,434 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 14.310000
PID25654 2017-04-05 16:04:01,439 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '0.84', 'stats_collisions': 
'96.13', 'stats_rawlognorm': '37 15.44 18.08 23.34 1.54', 'stats_optlognorm': 
'37 15.07 17.02 18.69 1.03'}
PID25654 2017-04-05 16:04:01,440 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '0.84', 'stats_collisions': '96.13', 
'stats_rawlognorm': '37 15.44 18.08 23.34 1.54', 'stats_optlognorm': '37 
15.07 17.02 18.69 1.03'}
PID25654 2017-04-05 16:04:01,446 Info:Polynomial Selection (size optimized): 
Marking workunit p50_polyselect1_0-500 as ok (50.0% => ETA Unknown)
PID25654 2017-04-05 16:04:01,486 Info:Polynomial Selection (size optimized): 
Parsed 55 polynomials, added 4 to priority queue (has 20)
PID25654 2017-04-05 16:04:01,486 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 14.170000
PID25654 2017-04-05 16:04:01,494 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '1.02', 'stats_collisions': 
'96.13', 'stats_rawlognorm': '55 16.02 18.14 19.67 0.83', 'stats_optlognorm': 
'55 15.48 17.3 19.51 0.79'}
PID25654 2017-04-05 16:04:01,495 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '1.8599999999999999', 
'stats_collisions': '192.26', 'stats_rawlognorm': '92 15.44 18.11586956521739 
23.34 1.1689754452863546', 'stats_optlognorm': '92 15.07 17.187391304347827 
19.51 0.9047758184564135'}
PID25654 2017-04-05 16:04:01,496 Info:Polynomial Selection (size optimized): 
Marking workunit p50_polyselect1_500-1000 as ok (100.0% => ETA Wed Apr  5 
16:04:01 2017)
PID25654 2017-04-05 16:04:01,502 Info:Polynomial Selection (size optimized): 
Finished
PID25654 2017-04-05 16:04:01,503 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID25654 2017-04-05 16:04:01,503 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID25654 2017-04-05 16:04:01,503 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 92/15.440/18.116/23.340/1.169
PID25654 2017-04-05 16:04:01,503 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 92/15.070/17.187/19.510/0.905
PID25654 2017-04-05 16:04:01,503 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID25654 2017-04-05 16:04:01,504 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID25654 2017-04-05 16:04:01,504 Info:Polynomial Selection (size optimized): 
Total time: 1.86
PID25654 2017-04-05 16:04:01,504 Info:Polynomial Selection (root optimized): 
Starting
PID25654 2017-04-05 16:04:01,504 Debug:Polynomial Selection (root optimized): 
polyselect2.run(): Task state: {'wu_failed': 0, 'start_real_time': 0, 
'wu_submitted': 0, 'wu_timedout': 0, 'wu_received': 0, 'nr_poly_submitted': 
0, 'start_achievement': -1}
PID25654 2017-04-05 16:04:01,504 Info:Polynomial Selection (root optimized): 
No polynomial was previously found
PID25654 2017-04-05 16:04:01,507 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID25654 2017-04-05 16:04:01,508 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_0 is 
('34980b0b95ae0211c5716e49ef0faa2305e61768', (10167576, 1597, 
1491397441.505024))
PID25654 2017-04-05 16:04:01,508 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID25654 2017-04-05 16:04:01,510 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt is 
('7e6a1b83979b2a60b7ede93b78d9ac390aa308f9', (212431667, 835453, 
1490019315.899416))
PID25654 2017-04-05 16:04:01,511 Debug:Server Launcher: Registering file name 
polyselect_ropt with target 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID25654 2017-04-05 16:04:01,517 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_0 with target /home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID25654 2017-04-05 16:04:01,522 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_0 to database
PID25654 2017-04-05 16:04:01,534 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID25654 2017-04-05 16:04:01,534 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_6 is 
('fc1c4b222fc14a2f1171fa71d460d6b146fb32c5', (10167582, 1605, 
1491397441.533024))
PID25654 2017-04-05 16:04:01,535 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_6 with target /home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID25654 2017-04-05 16:04:01,538 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_6 to database
PID25654 2017-04-05 16:04:01,545 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID25654 2017-04-05 16:04:01,546 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12 is 
('58f5a890f28abc86440562135beb713482d2bcd0', (10167583, 1596, 
1491397441.544024))
PID25654 2017-04-05 16:04:01,546 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_12 with target 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID25654 2017-04-05 16:04:01,550 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_12 to database
PID25654 2017-04-05 16:04:01,556 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID25654 2017-04-05 16:04:01,556 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18 is 
('99b4eb3b30217ca9f21961a6ba93e9d26b9fcf78', (10167724, 534, 
1491397441.555024))
PID25654 2017-04-05 16:04:01,557 Debug:Server Launcher: Registering file name 
p50.polyselect2.raw_18 with target 
/home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID25654 2017-04-05 16:04:01,561 Info:Polynomial Selection (root optimized): 
Adding workunit p50_polyselect2_18 to database
PID25654 2017-04-05 16:04:01,567 Debug:Polynomial Selection (root optimized): 
Setting last timeout check to 1491397441.567890
PID25654 2017-04-05 16:04:10,073 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_0 to client localhost+3
PID25654 2017-04-05 16:04:10,073 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:10,076 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_0 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_0
PID25654 2017-04-05 16:04:10,076 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_0 HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:10,078 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect_ropt to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID25654 2017-04-05 16:04:10,079 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect_ropt HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:10,114 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_6 to client localhost+4
PID25654 2017-04-05 16:04:10,114 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:10,117 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_6 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_6
PID25654 2017-04-05 16:04:10,117 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_6 HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:10,210 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_12 to client localhost+5
PID25654 2017-04-05 16:04:10,210 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:10,212 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_12 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_12
PID25654 2017-04-05 16:04:10,212 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_12 HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:10,299 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_polyselect2_18 to client localhost+6
PID25654 2017-04-05 16:04:10,299 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+6 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:10,309 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.polyselect2.raw_18 to /home/user/dlp/DLP50_1/p50.polyselect2.raw_18
PID25654 2017-04-05 16:04:10,309 Debug:HTTP server: 127.0.0.1 "GET 
/p50.polyselect2.raw_18 HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:14,011 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:14,011 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:14,608 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.8ci8gq2v.opt_18: Murphy E = 
1.22e-05
PID25654 2017-04-05 16:04:14,609 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 31415926535897932384626433832795028841971694002323
skew: 586.791
c0: 26738928110117
c1: -8174235023
c2: -18337338
c3: 37182
c4: 540
Y0: -481463785014
Y1: 519052169
# MurphyE (Bf=6.00e+05,Bg=6.00e+05,area=1.26e+12) = 1.22e-05
# f(x) = 540*x^4+37182*x^3-18337338*x^2-8174235023*x+26738928110117
# g(x) = 519052169*x-481463785014

PID25654 2017-04-05 16:04:14,611 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '6.99', 'stats_rootsieve_time': 
'6.55'}
PID25654 2017-04-05 16:04:14,611 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '6.99', 'stats_rootsieve_time': '6.55'}
PID25654 2017-04-05 16:04:14,617 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_18 as ok (10.0% => ETA Unknown)
PID25654 2017-04-05 16:04:19,175 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:19,176 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:19,638 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.1j4xjyrb.opt_0 with 
E=1.01e-05 is no better than current best with E=1.22e-05
PID25654 2017-04-05 16:04:19,639 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '17.47', 'stats_rootsieve_time': 
'16.5'}
PID25654 2017-04-05 16:04:19,639 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '24.46', 'stats_rootsieve_time': '23.05'}
PID25654 2017-04-05 16:04:19,640 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_0 as ok (40.0% => ETA Wed Apr  5 16:04:55 
2017)
PID25654 2017-04-05 16:04:20,075 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:20,075 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:20,197 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:20,197 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:04:20,648 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.35e_l5bl.opt_6: Murphy E = 
1.37e-05
PID25654 2017-04-05 16:04:20,649 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 31415926535897932384626433832795028841971694002323
skew: 754.826
c0: 141282195048885
c1: -508760385131
c2: -1287014117
c3: 572823
c4: 180
Y0: -68624570812
Y1: 985734191
# MurphyE (Bf=6.00e+05,Bg=6.00e+05,area=1.26e+12) = 1.37e-05
# f(x) = 180*x^4+572823*x^3-1287014117*x^2-508760385131*x+141282195048885
# g(x) = 985734191*x-68624570812

PID25654 2017-04-05 16:04:20,652 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '18.91', 'stats_rootsieve_time': 
'17.87'}
PID25654 2017-04-05 16:04:20,652 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '43.370000000000005', 
'stats_rootsieve_time': '40.92'}
PID25654 2017-04-05 16:04:20,653 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_6 as ok (70.0% => ETA Wed Apr  5 16:04:30 
2017)
PID25654 2017-04-05 16:04:20,660 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.mvpnxcyz.opt_12 with 
E=1.14e-05 is no better than current best with E=1.37e-05
PID25654 2017-04-05 16:04:20,661 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '17.64', 'stats_rootsieve_time': 
'16.65'}
PID25654 2017-04-05 16:04:20,662 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '61.010000000000005', 
'stats_rootsieve_time': '57.57'}
PID25654 2017-04-05 16:04:20,663 Info:Polynomial Selection (root optimized): 
Marking workunit p50_polyselect2_12 as ok (100.0% => ETA Wed Apr  5 16:04:20 
2017)
PID25654 2017-04-05 16:04:20,667 Info:Polynomial Selection (root optimized): 
Finished, best polynomial from file 
/home/user/dlp/DLP50_1/p50.upload/p50.polyselect2.35e_l5bl.opt_6 has Murphy_E 
= 1.37e-05
PID25654 2017-04-05 16:04:20,668 Info:Polynomial Selection (root optimized): 
Best overall polynomial was 10-th in list after size optimization
PID25654 2017-04-05 16:04:20,673 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID25654 2017-04-05 16:04:20,673 Info:Polynomial Selection (root optimized): 
Total time: 61.01
PID25654 2017-04-05 16:04:20,673 Info:Polynomial Selection (root optimized): 
Rootsieve time: 57.57
PID25654 2017-04-05 16:04:20,673 Info:Number Theory for DLP: Starting
PID25654 2017-04-05 16:04:20,674 Debug:Number Theory for DLP: 
numbertheory.run(): Task state: {}
PID25654 2017-04-05 16:04:20,688 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/badideals -poly 
/home/user/dlp/DLP50_1/p50.poly -ell 
15707963267948966192313216916397514420985847001161 -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -badideals 
/home/user/dlp/DLP50_1/p50.badideals > 
/home/user/dlp/DLP50_1/p50.badideals.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.badideals.stderr.1
PID25654 2017-04-05 16:04:20,847 Debug:Command: Process with PID 25703 
finished successfully
PID25654 2017-04-05 16:04:20,857 Debug:Number Theory for DLP: Exit 
NumberTheoryTask.run(numbertheory)
PID25654 2017-04-05 16:04:20,857 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.16/0.159016
PID25654 2017-04-05 16:04:20,857 Info:Generate Factor Base: Starting
PID25654 2017-04-05 16:04:20,858 Debug:Generate Factor Base: 
factorbase.run(): Task state: {}
PID25654 2017-04-05 16:04:20,870 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/makefb -poly 
/home/user/dlp/DLP50_1/p50.poly -lim 600000 -maxbits 11 -out 
/home/user/dlp/DLP50_1/p50.roots.gz -t 11 > 
/home/user/dlp/DLP50_1/p50.makefb.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.makefb.stderr.1
PID25654 2017-04-05 16:04:21,233 Debug:Command: Process with PID 25704 
finished successfully
PID25654 2017-04-05 16:04:21,240 Info:Generate Factor Base: Finished
PID25654 2017-04-05 16:04:21,241 Info:Generate Factor Base: Total cpu/real 
time for makefb: 2.13/0.362458
PID25654 2017-04-05 16:04:21,241 Info:Generate Free Relations: Starting
PID25654 2017-04-05 16:04:21,241 Debug:Generate Free Relations: 
freerel.run(): Task state: {}
PID25654 2017-04-05 16:04:21,253 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/freerel -poly 
/home/user/dlp/DLP50_1/p50.poly -renumber 
/home/user/dlp/DLP50_1/p50.renumber.gz -lpb0 22 -lpb1 22 -out 
/home/user/dlp/DLP50_1/p50.freerel.gz -badideals 
/home/user/dlp/DLP50_1/p50.badideals -lcideals -t 11 > 
/home/user/dlp/DLP50_1/p50.freerel.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.freerel.stderr.1
PID25654 2017-04-05 16:04:22,893 Debug:Command: Process with PID 25763 
finished successfully
PID25654 2017-04-05 16:04:22,963 Info:Generate Free Relations: Found 12059 
free relations
PID25654 2017-04-05 16:04:22,963 Info:Generate Free Relations: Finished
PID25654 2017-04-05 16:04:22,964 Info:Generate Free Relations: Total cpu/real 
time for freerel: 14.2/1.64009
PID25654 2017-04-05 16:04:22,964 Info:Lattice Sieving: Starting
PID25654 2017-04-05 16:04:22,965 Debug:Lattice Sieving: sieving.run(): Task 
state: {'wu_failed': 0, 'start_real_time': 0, 'wu_submitted': 0, 
'wu_timedout': 0, 'wu_received': 0, 'rels_wanted': 800000, 'rels_found': 0, 
'qnext': 35, 'start_achievement': -1}
PID25654 2017-04-05 16:04:22,967 Info:Lattice Sieving: We want 800000 
relations
PID25654 2017-04-05 16:04:22,970 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.poly
PID25654 2017-04-05 16:04:22,970 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.poly is 
('2016b206770180d5ef38bd95dc901835cebb84c7', (10210721, 340, 
1491397460.668024))
PID25654 2017-04-05 16:04:22,971 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/DLP50_1/p50.roots.gz
PID25654 2017-04-05 16:04:22,972 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/DLP50_1/p50.roots.gz is 
('02195fe9dc5514b3d2ce57c39ff8e9a4cf1e6d39', (10210731, 263835, 
1491397461.231024))
PID25654 2017-04-05 16:04:22,973 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID25654 2017-04-05 16:04:22,997 Debug:Sha1Cache: SHA1 for file 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las is 
('3bb32a4292cff9c1d8e291684e33816cee97e62f', (10167295, 7121242, 
1490019316.707416))
PID25654 2017-04-05 16:04:23,001 Debug:Server Launcher: Registering file name 
las with target /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID25654 2017-04-05 16:04:23,006 Debug:Server Launcher: Registering file name 
p50.poly with target /home/user/dlp/DLP50_1/p50.poly
PID25654 2017-04-05 16:04:23,013 Debug:Server Launcher: Registering file name 
p50.roots.gz with target /home/user/dlp/DLP50_1/p50.roots.gz
PID25654 2017-04-05 16:04:23,018 Info:Lattice Sieving: Adding workunit 
p50_sieving_35-1000 to database
PID25654 2017-04-05 16:04:23,031 Info:Lattice Sieving: Adding workunit 
p50_sieving_1000-2000 to database
PID25654 2017-04-05 16:04:23,037 Info:Lattice Sieving: Adding workunit 
p50_sieving_2000-3000 to database
PID25654 2017-04-05 16:04:23,046 Info:Lattice Sieving: Adding workunit 
p50_sieving_3000-4000 to database
PID25654 2017-04-05 16:04:23,054 Info:Lattice Sieving: Adding workunit 
p50_sieving_4000-5000 to database
PID25654 2017-04-05 16:04:23,061 Info:Lattice Sieving: Adding workunit 
p50_sieving_5000-6000 to database
PID25654 2017-04-05 16:04:23,068 Info:Lattice Sieving: Adding workunit 
p50_sieving_6000-7000 to database
PID25654 2017-04-05 16:04:23,075 Info:Lattice Sieving: Adding workunit 
p50_sieving_7000-8000 to database
PID25654 2017-04-05 16:04:23,083 Info:Lattice Sieving: Adding workunit 
p50_sieving_8000-9000 to database
PID25654 2017-04-05 16:04:23,090 Info:Lattice Sieving: Adding workunit 
p50_sieving_9000-10000 to database
PID25654 2017-04-05 16:04:23,096 Debug:Lattice Sieving: Setting last timeout 
check to 1491397463.096817
PID25654 2017-04-05 16:04:24,046 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_35-1000 to client localhost+6
PID25654 2017-04-05 16:04:24,047 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+6 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:24,050 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.poly to /home/user/dlp/DLP50_1/p50.poly
PID25654 2017-04-05 16:04:24,050 Debug:HTTP server: 127.0.0.1 "GET /p50.poly 
HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:24,053 Debug:HTTP server: 127.0.0.1 Translated path 
/p50.roots.gz to /home/user/dlp/DLP50_1/p50.roots.gz
PID25654 2017-04-05 16:04:24,053 Debug:HTTP server: 127.0.0.1 "GET 
/p50.roots.gz HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:24,059 Debug:HTTP server: 127.0.0.1 Translated path 
/las to /home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/sieve/las
PID25654 2017-04-05 16:04:24,059 Debug:HTTP server: 127.0.0.1 "GET /las 
HTTP/1.1" HTTPStatus.OK -
PID25654 2017-04-05 16:04:24,098 Info:Lattice Sieving: Adding workunit 
p50_sieving_10000-11000 to database
PID25654 2017-04-05 16:04:29,213 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_1000-2000 to client localhost+3
PID25654 2017-04-05 16:04:29,213 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:30,105 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_2000-3000 to client localhost+5
PID25654 2017-04-05 16:04:30,105 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:30,127 Info:Lattice Sieving: Adding workunit 
p50_sieving_11000-12000 to database
PID25654 2017-04-05 16:04:30,157 Info:Lattice Sieving: Adding workunit 
p50_sieving_12000-13000 to database
PID25654 2017-04-05 16:04:30,234 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_3000-4000 to client localhost+4
PID25654 2017-04-05 16:04:30,234 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:30,430 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_4000-5000 to client localhost
PID25654 2017-04-05 16:04:30,431 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:30,738 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_5000-6000 to client localhost+2
PID25654 2017-04-05 16:04:30,740 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID25654 2017-04-05 16:04:31,188 Info:Lattice Sieving: Adding workunit 
p50_sieving_13000-14000 to database
PID25654 2017-04-05 16:04:31,215 Info:Lattice Sieving: Adding workunit 
p50_sieving_14000-15000 to database
PID25654 2017-04-05 16:04:31,265 Info:Lattice Sieving: Adding workunit 
p50_sieving_15000-16000 to database
PID25654 2017-04-05 16:05:48,381 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:05:48,384 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:05:50,064 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_6000-7000 to client localhost+2
PID25654 2017-04-05 16:05:50,064 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID25654 2017-04-05 16:05:50,841 Info:Lattice Sieving: Adding workunit 
p50_sieving_16000-17000 to database
PID25654 2017-04-05 16:05:50,858 Debug:Lattice Sieving: stderr is: b"# 
Average J=991 for 100 special-q's, max bucket fill 0.615493\n# Discarded 0 
special-q's out of 100 pushed\n# Total cpu time 77.68s [norm 0.06+1.1, 
sieving 25.8 (19.8 + 1.8 + 4.2), factor 50.7 (48.6 + 2.1)]\n# Total elapsed 
time 76.53s, per special-q 0.765303s, per relation 0.000132782s\n# 
PeakMemusage (MB) = 229 \n# Total 576359 reports [0.000135s/r, 5763.6r/sq]\n"
PID25654 2017-04-05 16:05:50,862 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.615493', 'stats_avg_J': '991.0 100', 
'stats_total_cpu_time': '77.68'}
PID25654 2017-04-05 16:05:50,863 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.615493', 'stats_avg_J': '991.0 100', 
'stats_total_cpu_time': '77.68'}
PID25654 2017-04-05 16:05:50,864 Info:Lattice Sieving: Found 576359 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.3c6jexv4.gz', total is 
now 576359/800000
PID25654 2017-04-05 16:05:50,879 Info:Lattice Sieving: Marking workunit 
p50_sieving_5000-6000 as ok (72.0% => ETA Unknown)
PID25654 2017-04-05 16:06:08,728 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:08,730 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:06:10,483 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_7000-8000 to client localhost
PID25654 2017-04-05 16:06:10,483 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:11,019 Info:Lattice Sieving: Adding workunit 
p50_sieving_17000-18000 to database
PID25654 2017-04-05 16:06:11,039 Debug:Lattice Sieving: stderr is: b"# 
Average J=1002 for 122 special-q's, max bucket fill 0.619571\n# Discarded 0 
special-q's out of 122 pushed\n# Total cpu time 99.11s [norm 0.10+1.4, 
sieving 32.3 (24.7 + 2.4 + 5.2), factor 65.4 (62.6 + 2.7)]\n# Total elapsed 
time 96.93s, per special-q 0.794488s, per relation 0.000130905s\n# 
PeakMemusage (MB) = 229 \n# Total 740441 reports [0.000134s/r, 6069.2r/sq]\n"
PID25654 2017-04-05 16:06:11,040 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.619571', 'stats_avg_J': '1002.0 122', 
'stats_total_cpu_time': '99.11'}
PID25654 2017-04-05 16:06:11,040 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.619571', 'stats_avg_J': '997.0450450450451 222', 
'stats_total_cpu_time': '176.79000000000002'}
PID25654 2017-04-05 16:06:11,042 Info:Lattice Sieving: Found 740441 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.he1w5ngz.gz', total is 
now 1316800/800000
PID25654 2017-04-05 16:06:11,042 Info:Lattice Sieving: Marking workunit 
p50_sieving_4000-5000 as ok (164.6% => ETA Wed Apr  5 16:04:55 2017)
PID25654 2017-04-05 16:06:22,000 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:22,001 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:06:24,549 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:24,550 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:06:25,219 Debug:Lattice Sieving: stderr is: b"# 
Average J=1022 for 130 special-q's, max bucket fill 0.621075\n# Discarded 0 
special-q's out of 130 pushed\n# Total cpu time 109.99s [norm 0.08+1.5, 
sieving 34.6 (26.7 + 2.4 + 5.5), factor 73.9 (70.7 + 3.2)]\n# Total elapsed 
time 110.45s, per special-q 0.849595s, per relation 0.000128022s\n# 
PeakMemusage (MB) = 229 \n# Total 862723 reports [0.000127s/r, 6636.3r/sq]\n"
PID25654 2017-04-05 16:06:25,222 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.621075', 'stats_avg_J': '1022.0 130', 
'stats_total_cpu_time': '109.99'}
PID25654 2017-04-05 16:06:25,222 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.621075', 'stats_avg_J': '1006.2613636363636 
352', 'stats_total_cpu_time': '286.78000000000003'}
PID25654 2017-04-05 16:06:25,223 Info:Lattice Sieving: Found 862723 relations 
in '/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.g1kvclt1.gz', total is 
now 2179523/800000
PID25654 2017-04-05 16:06:25,224 Info:Lattice Sieving: Marking workunit 
p50_sieving_3000-4000 as ok (272.4% => ETA Wed Apr  5 16:04:40 2017)
PID25654 2017-04-05 16:06:26,614 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_8000-9000 to client localhost+4
PID25654 2017-04-05 16:06:26,614 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:26,625 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_9000-10000 to client localhost+5
PID25654 2017-04-05 16:06:26,625 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:27,271 Info:Lattice Sieving: Adding workunit 
p50_sieving_18000-19000 to database
PID25654 2017-04-05 16:06:27,308 Info:Lattice Sieving: Reached target of 
800000 relations, now have 2179523
PID25654 2017-04-05 16:06:27,310 Debug:Lattice Sieving: Exit 
SievingTask.run(sieving)
PID25654 2017-04-05 16:06:27,310 Info:Lattice Sieving: Aggregate statistics:
PID25654 2017-04-05 16:06:27,310 Info:Lattice Sieving: Total number of 
relations: 2179523
PID25654 2017-04-05 16:06:27,310 Info:Lattice Sieving: Average J: 1006.26 for 
352 special-q, max bucket fill: 0.621075
PID25654 2017-04-05 16:06:27,310 Info:Lattice Sieving: Total CPU time: 286.78s
PID25654 2017-04-05 16:06:27,311 Info:Filtering - Duplicate Removal, 
splitting pass: Starting
PID25654 2017-04-05 16:06:27,311 Debug:Filtering - Duplicate Removal, 
splitting pass: duplicates1.run(): Task state: {}
PID25654 2017-04-05 16:06:27,313 Debug:Filtering - Duplicate Removal, 
splitting pass: new files to split are: 
['/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.he1w5ngz.gz', 
'/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.g1kvclt1.gz', 
'/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.3c6jexv4.gz']
PID25654 2017-04-05 16:06:27,314 Info:Filtering - Duplicate Removal, 
splitting pass: Splitting 3 new files
PID25654 2017-04-05 16:06:27,347 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup1 -prefix dup1.0 -out 
/home/user/dlp/DLP50_1/p50.dup1/ -n 1 
/home/user/dlp/DLP50_1/p50.upload/p50.4000-5000.he1w5ngz.gz 
/home/user/dlp/DLP50_1/p50.upload/p50.3000-4000.g1kvclt1.gz 
/home/user/dlp/DLP50_1/p50.upload/p50.5000-6000.3c6jexv4.gz > 
/home/user/dlp/DLP50_1/p50.dup1.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup1.stderr.1
PID25654 2017-04-05 16:06:35,802 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:35,806 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID25654 2017-04-05 16:06:35,810 Debug:Command: Process with PID 25882 
finished successfully
PID25654 2017-04-05 16:06:35,850 Debug:Filtering - Duplicate Removal, 
splitting pass: Output file names: 
{'/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz': 1, 
'/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz': 0}
PID25654 2017-04-05 16:06:35,872 Debug:Filtering - Duplicate Removal, 
splitting pass: Newly arrived stats: {'stats_dup1_time': '8.2'}
PID25654 2017-04-05 16:06:35,872 Debug:Filtering - Duplicate Removal, 
splitting pass: Combined stats: {'stats_dup1_time': '8.2'}
PID25654 2017-04-05 16:06:35,894 Info:Filtering - Duplicate Removal, 
splitting pass: Relations per slice: 0: 1089979, 1: 1089544
PID25654 2017-04-05 16:06:35,895 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Duplicates1Task.run(duplicates1)
PID25654 2017-04-05 16:06:35,895 Info:Filtering - Duplicate Removal, 
splitting pass: Total cpu/real time for dup1: 8.1/8.46126
PID25654 2017-04-05 16:06:35,895 Info:Filtering - Duplicate Removal, 
splitting pass: Aggregate statistics:
PID25654 2017-04-05 16:06:35,896 Info:Filtering - Duplicate Removal, 
splitting pass: CPU time for dup1: 8.2s
PID25654 2017-04-05 16:06:35,896 Info:Filtering - Duplicate Removal, removal 
pass: Starting
PID25654 2017-04-05 16:06:35,896 Debug:Filtering - Duplicate Removal, removal 
pass: duplicates2.run(): Task state: {}
PID25654 2017-04-05 16:06:36,018 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup2 -nrels 1089979 
-renumber /home/user/dlp/DLP50_1/p50.renumber.gz -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -dl 
/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz > 
/home/user/dlp/DLP50_1/p50.dup2.slice0.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup2.slice0.stderr.1
PID25654 2017-04-05 16:06:38,405 Info:HTTP server: 127.0.0.1 Sending workunit 
p50_sieving_10000-11000 to client localhost+3
PID25654 2017-04-05 16:06:38,405 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID25654 2017-04-05 16:06:42,740 Debug:Command: Process with PID 25896 
finished successfully
PID25654 2017-04-05 16:06:42,790 Info:Filtering - Duplicate Removal, removal 
pass: 990817 unique relations remain on slice 0
PID25654 2017-04-05 16:06:42,902 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/dup2 -nrels 1089544 
-renumber /home/user/dlp/DLP50_1/p50.renumber.gz -badidealinfo 
/home/user/dlp/DLP50_1/p50.badidealinfo -dl 
/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz > 
/home/user/dlp/DLP50_1/p50.dup2.slice1.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.dup2.slice1.stderr.1
PID25654 2017-04-05 16:06:50,377 Debug:Command: Process with PID 25931 
finished successfully
PID25654 2017-04-05 16:06:50,419 Info:Filtering - Duplicate Removal, removal 
pass: 990763 unique relations remain on slice 1
PID25654 2017-04-05 16:06:50,431 Info:Filtering - Duplicate Removal, removal 
pass: Of 2179523 newly added relations 1981580 were unique (ratio 0.909181)
PID25654 2017-04-05 16:06:50,446 Info:Filtering - Duplicate Removal, removal 
pass: 1981580 unique relations remain in total
PID25654 2017-04-05 16:06:50,446 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Duplicates2Task.run(duplicates2)
PID25654 2017-04-05 16:06:50,446 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 31.37/14.1955
PID25654 2017-04-05 16:06:50,447 Info:Filtering - Singleton removal: Starting
PID25654 2017-04-05 16:06:50,447 Debug:Filtering - Singleton removal: 
purge.run(): Task state: {'input_nrels': 0}
PID25654 2017-04-05 16:06:50,469 Info:Filtering - Singleton removal: Reading 
1981580 unique and 12059 free relations, total 1993639
PID25654 2017-04-05 16:06:50,513 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/purge -out 
/home/user/dlp/DLP50_1/p50.purged.gz -nrels 1993639 -outdel 
/home/user/dlp/DLP50_1/p50.relsdel.gz -keep 3 -col-min-index 14780 
-col-max-index 591211 -t 11 -required_excess 0.0 
/home/user/dlp/DLP50_1/p50.dup1//1/dup1.0.0000.gz 
/home/user/dlp/DLP50_1/p50.dup1//0/dup1.0.0000.gz 
/home/user/dlp/DLP50_1/p50.freerel.gz > 
/home/user/dlp/DLP50_1/p50.purge.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.purge.stderr.1
PID25654 2017-04-05 16:07:25,863 Debug:Command: Process with PID 25950 
finished successfully
PID25654 2017-04-05 16:07:25,899 Info:Filtering - Singleton removal: After 
purge, 14781 relations with 14778 primes remain with weight 308245 and excess 
3
PID25654 2017-04-05 16:07:25,910 Info:Filtering - Singleton removal: Have 
enough relations
PID25654 2017-04-05 16:07:25,910 Info:HTTP server: Got notification to stop 
serving Workunits
PID25654 2017-04-05 16:07:25,911 Info:Lattice Sieving: Cancelling remaining 
workunits
PID25654 2017-04-05 16:07:25,931 Command:Command: /bin/kill 25660
PID25654 2017-04-05 16:07:25,932 Debug:Command: Process with PID 28380 
finished successfully
PID25654 2017-04-05 16:07:25,932 Info:Client Launcher: Stopped client 
localhost+2 (Host localhost, PID 25660)
PID25654 2017-04-05 16:07:25,946 Command:Command: /bin/kill 25662
PID25654 2017-04-05 16:07:25,947 Debug:Command: Process with PID 28381 
finished successfully
PID25654 2017-04-05 16:07:25,947 Info:Client Launcher: Stopped client 
localhost+3 (Host localhost, PID 25662)
PID25654 2017-04-05 16:07:25,960 Command:Command: /bin/kill 25666
PID25654 2017-04-05 16:07:25,962 Debug:Command: Process with PID 28382 
finished successfully
PID25654 2017-04-05 16:07:25,962 Info:Client Launcher: Stopped client 
localhost+5 (Host localhost, PID 25666)
PID25654 2017-04-05 16:07:25,983 Command:Command: /bin/kill 25676
PID25654 2017-04-05 16:07:25,984 Debug:Command: Process with PID 28383 
finished successfully
PID25654 2017-04-05 16:07:25,984 Info:Client Launcher: Stopped client 
localhost+6 (Host localhost, PID 25676)
PID25654 2017-04-05 16:07:25,993 Command:Command: /bin/kill 25658
PID25654 2017-04-05 16:07:25,994 Debug:Command: Process with PID 28384 
finished successfully
PID25654 2017-04-05 16:07:25,995 Info:Client Launcher: Stopped client 
localhost (Host localhost, PID 25658)
PID25654 2017-04-05 16:07:26,007 Command:Command: /bin/kill 25664
PID25654 2017-04-05 16:07:26,007 Debug:Command: Process with PID 28385 
finished successfully
PID25654 2017-04-05 16:07:26,007 Info:Client Launcher: Stopped client 
localhost+4 (Host localhost, PID 25664)
PID25654 2017-04-05 16:07:26,011 Debug:Filtering - Singleton removal: Exit 
PurgeTask.run(purge)
PID25654 2017-04-05 16:07:26,011 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 86.91/35.35
PID25654 2017-04-05 16:07:26,011 Info:Filtering - Merging: Starting
PID25654 2017-04-05 16:07:26,012 Debug:Filtering - Merging: mergedlp.run(): 
Task state: {}
PID25654 2017-04-05 16:07:26,019 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/merge-dl -mat 
/home/user/dlp/DLP50_1/p50.purged.gz -out 
/home/user/dlp/DLP50_1/p50.history.gz -maxlevel 15 -keep 3 -skip 0 
-target_density 100.0 -t 11 > /home/user/dlp/DLP50_1/p50.merge-dl.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.merge-dl.stderr.1
PID25654 2017-04-05 16:07:26,696 Debug:Command: Process with PID 28386 
finished successfully
PID25654 2017-04-05 16:07:26,700 Info:Filtering - Merging: Merged matrix has 
2737 rows and total weight 269955 (98.6 entries per row on average)
PID25654 2017-04-05 16:07:26,705 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/replay-dl -purged 
/home/user/dlp/DLP50_1/p50.purged.gz -ideals /home/user/dlp/DLP50_1/p50.ideal 
-his /home/user/dlp/DLP50_1/p50.history.gz -index 
/home/user/dlp/DLP50_1/p50.index.gz -out 
/home/user/dlp/DLP50_1/p50.sparse.bin > 
/home/user/dlp/DLP50_1/p50.replay.stdout.2 2> 
/home/user/dlp/DLP50_1/p50.replay.stderr.2
PID25654 2017-04-05 16:07:26,868 Debug:Command: Process with PID 28409 
finished successfully
PID25654 2017-04-05 16:07:26,874 Debug:Filtering - Merging: Exit 
MergeDLPTask.run(mergedlp)
PID25654 2017-04-05 16:07:26,874 Info:Filtering - Merging: Total cpu/real 
time for merge-dl: 0.58/0.676929
PID25654 2017-04-05 16:07:26,874 Info:Filtering - Merging: Total cpu/real 
time for replay-dl: 0.14/0.162277
PID25654 2017-04-05 16:07:26,874 Info:Schirokauer Maps: Starting
PID25654 2017-04-05 16:07:26,874 Debug:Schirokauer Maps: sm.run(): Task 
state: {}
PID25654 2017-04-05 16:07:26,881 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/sm -poly 
/home/user/dlp/DLP50_1/p50.poly -purged /home/user/dlp/DLP50_1/p50.purged.gz 
-index /home/user/dlp/DLP50_1/p50.index.gz -out /home/user/dlp/DLP50_1/p50.sm 
-ell 15707963267948966192313216916397514420985847001161 -nsm '0,3' -t 11 > 
/home/user/dlp/DLP50_1/p50.sm.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.sm.stderr.1
PID25654 2017-04-05 16:07:30,994 Debug:Command: Process with PID 28420 
finished successfully
PID25654 2017-04-05 16:07:31,014 Debug:Schirokauer Maps: Exit SMTask.run(sm)
PID25654 2017-04-05 16:07:31,014 Info:Schirokauer Maps: Total cpu/real time 
for sm: 36.68/4.11266
PID25654 2017-04-05 16:07:31,015 Info:Linear Algebra: Starting
PID25654 2017-04-05 16:07:31,015 Debug:Linear Algebra: linalgdlp.run(): Task 
state: {'ran_already': False}
PID25654 2017-04-05 16:07:31,029 Info:Linear Algebra: Using 3 as default 
value for n to account for Schirokauer maps
PID25654 2017-04-05 16:07:31,029 Info:Linear Algebra: Using 2*n=6 as default 
value for m
PID25654 2017-04-05 16:07:31,033 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl :complete 
'allow_zero_on_rhs=1' 'thr=11' 'm=6' 'n=3' 'nullspace=right' 'interval=1000' 
'matrix=/home/user/dlp/DLP50_1/p50.sparse.bin' 
'rhs=/home/user/dlp/DLP50_1/p50.sm' 
'prime=15707963267948966192313216916397514420985847001161' 
'wdir=/home/user/dlp/DLP50_1/p50.bwc' 'cpubinding=false' 
'cantor_threshold=2048' > /home/user/dlp/DLP50_1/p50.bwc.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.bwc.stderr.1
PID25654 2017-04-05 16:09:43,979 Debug:Command: Process with PID 28446 
finished successfully
PID25654 2017-04-05 16:09:43,988 Debug:Linear Algebra: Exit 
LinAlgTask.run(linalgdlp)
PID25654 2017-04-05 16:09:43,988 Info:Linear Algebra: Total cpu/real time for 
bwc: 195.81/132.946
PID25654 2017-04-05 16:09:43,988 Info:Logarithms Reconstruction: Starting
PID25654 2017-04-05 16:09:43,988 Debug:Logarithms Reconstruction: 
reconstructlog.run(): Task state: {}
PID25654 2017-04-05 16:09:44,002 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/filter/reconstructlog-dl -ell 
15707963267948966192313216916397514420985847001161 -mt 11 -log 
/home/user/dlp/DLP50_1/p50.bwc/K.sols0-1.0.txt -out 
/home/user/dlp/DLP50_1/p50.dlog -renumber 
/home/user/dlp/DLP50_1/p50.renumber.gz -poly /home/user/dlp/DLP50_1/p50.poly 
-purged /home/user/dlp/DLP50_1/p50.purged.gz -ideals 
/home/user/dlp/DLP50_1/p50.ideal -relsdel 
/home/user/dlp/DLP50_1/p50.relsdel.gz -nrels 1993639 -nsm '0,3' > 
/home/user/dlp/DLP50_1/p50.reconstructlog-dl.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.reconstructlog-dl.stderr.1
PID25654 2017-04-05 16:56:42,456 Debug:Command: Process with PID 28585 
finished successfully
PID25654 2017-04-05 16:56:42,470 Debug:Logarithms Reconstruction: Exit 
ReconstructLogTask.run(reconstructlog)
PID25654 2017-04-05 16:56:42,470 Info:Logarithms Reconstruction: Total 
cpu/real time for reconstructlog-dl: 26910.6/2818.45
PID25654 2017-04-05 16:56:42,470 Info:Individual logarithm: Starting
PID25654 2017-04-05 16:56:42,470 Debug:Individual logarithm: descent.run(): 
Task state: {}
PID25654 2017-04-05 16:56:42,490 Command:Command: 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1/scripts/descent.py --target 
24509473578884360875565352586814397447467113190347 --prefix p50 --datadir 
/home/user/dlp/DLP50_1 --cadobindir 
/home/user/cado/cado_mpi/lib/cado-nfs-2.2.1 --descent-hint 
/home/user/cado/cado_mpi/share/cado-nfs-2.2.1/dlp/p50.hint --init-I 11 
--init-ncurves 10 --init-lpb 24 --init-lim 100000 --init-mfb 48 
--init-tkewness 200000 --I 11 --lpb0 22 --lpb1 22 --mfb0 44 --mfb1 44 --lim0 
100000 --lim1 100000 --ell 15707963267948966192313216916397514420985847001161 
> /home/user/dlp/DLP50_1/p50.descent.stdout.1 2> 
/home/user/dlp/DLP50_1/p50.descent.stderr.1
PID25654 2017-04-05 16:56:45,653 Debug:Command: Process with PID 6722 
finished successfully
PID25654 2017-04-05 16:56:45,659 Info:Individual logarithm: Total cpu/real 
time for descent: 3.07/3.16262
PID25654 2017-04-05 16:56:45,659 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 92/15.440/18.116/23.340/1.169
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 92/15.070/17.187/19.510/0.905
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (size optimized): 
Total time: 1.86
PID25654 2017-04-05 16:56:45,660 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID25654 2017-04-05 16:56:45,661 Info:Polynomial Selection (root optimized): 
Total time: 61.01
PID25654 2017-04-05 16:56:45,661 Info:Polynomial Selection (root optimized): 
Rootsieve time: 57.57
PID25654 2017-04-05 16:56:45,661 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.16/0.159016
PID25654 2017-04-05 16:56:45,661 Info:Generate Factor Base: Total cpu/real 
time for makefb: 2.13/0.362458
PID25654 2017-04-05 16:56:45,661 Info:Generate Free Relations: Total cpu/real 
time for freerel: 14.2/1.64009
PID25654 2017-04-05 16:56:45,661 Info:Lattice Sieving: Aggregate statistics:
PID25654 2017-04-05 16:56:45,661 Info:Lattice Sieving: Total number of 
relations: 2179523
PID25654 2017-04-05 16:56:45,662 Info:Lattice Sieving: Average J: 1006.26 for 
352 special-q, max bucket fill: 0.621075
PID25654 2017-04-05 16:56:45,662 Info:Lattice Sieving: Total CPU time: 286.78s
PID25654 2017-04-05 16:56:45,662 Info:Filtering - Duplicate Removal, 
splitting pass: Total cpu/real time for dup1: 8.1/8.46126
PID25654 2017-04-05 16:56:45,662 Info:Filtering - Duplicate Removal, 
splitting pass: Aggregate statistics:
PID25654 2017-04-05 16:56:45,662 Info:Filtering - Duplicate Removal, 
splitting pass: CPU time for dup1: 8.2s
PID25654 2017-04-05 16:56:45,662 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 31.37/14.1955
PID25654 2017-04-05 16:56:45,663 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 86.91/35.35
PID25654 2017-04-05 16:56:45,663 Info:Filtering - Merging: Total cpu/real 
time for merge-dl: 0.58/0.676929
PID25654 2017-04-05 16:56:45,663 Info:Filtering - Merging: Total cpu/real 
time for replay-dl: 0.14/0.162277
PID25654 2017-04-05 16:56:45,663 Info:Schirokauer Maps: Total cpu/real time 
for sm: 36.68/4.11266
PID25654 2017-04-05 16:56:45,663 Info:Linear Algebra: Total cpu/real time for 
bwc: 195.81/132.946
PID25654 2017-04-05 16:56:45,663 Info:Logarithms Reconstruction: Total 
cpu/real time for reconstructlog-dl: 26910.6/2818.45
PID25654 2017-04-05 16:56:45,664 Info:Individual logarithm: Total cpu/real 
time for descent: 3.07/3.16262
PID25654 2017-04-05 16:56:45,664 Info:HTTP server: Shutting down HTTP server
PID25654 2017-04-05 16:56:45,905 Info:Complete Factorization: Total 
cpu/elapsed time for entire discrete log: 27639.4/3176.15
PID25654 2017-04-05 16:56:45,985 Info:root: If you want to compute a new 
target, run ./cado-nfs.py /home/user/dlp/DLP50_1/p50.parameters_snapshot.0 
target=<target>