Discussion related to cado-nfs

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

Hello! In some cases, the computation of an individual logarithm takes a very 
long time. The descent In the file.you receive a stream of output to stdout # 
to walk the path: 20@0 -> loop (#1) 0 980963 334145... It's related in the 
fact that the found correlations not found the logarithm of the target ? When 
reducing tasks.sieve.qmin this problem does not occur. I would like to know 
what caused this problem and how to solve it. To the letter attach 4 files 
with examples. Target p60_1 is present this  trouble.

-- 
Best regards
Бобров Х.

Attachment: p60.descent.stdout.1
Description: Binary data

PID6803 2017-02-27 14:00:39,725 Info:root: Command line parameters: 
./cado-nfs.py -dlp -ell 
157079632679489661923132169163975144209858469968755291072921 target=4 
314159265358979323846264338327950288419716939937510582145843 server.ssl=no -w 
/home/user/dlp/descent
PID6803 2017-02-27 14:00:39,726 Debug:root: Root parameter dictionary:
N = 314159265358979323846264338327950288419716939937510582145843
descent_hint = $CADO_DIR/share/cado-nfs-2.2.1/dlp/p60.hint
dlp = True
ell = 157079632679489661923132169163975144209858469968755291072921
gfpext = 1
lim0 = 500000
lim1 = 600000
lpb0 = 22
lpb1 = 22
name = p60
target = 4
server.ssl = no
slaves.basepath = /home/user/dlp/descent/client
slaves.hostnames = localhost
slaves.nrclients = 5
slaves.scriptpath = $CADO_DIR/bin
tasks.I = 11
tasks.execpath = $CADO_DIR/lib/cado-nfs-2.2.1
tasks.threads = 10
tasks.workdir = /home/user/dlp/descent
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 = 500000
tasks.descent.lim1 = 600000
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 = 
$CADO_DIR/share/cado-nfs-2.2.1/misc/cpubinding.conf
tasks.linalg.bwc.thr = 4x2
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 = 1
tasks.sieve.qrange = 1000
tasks.sieve.rels_wanted = 1000000
tasks.sieve.las.threads = 2
tasks.sqrt.sqrt.threads = 8
PID6803 2017-02-27 14:00:39,726 Debug:root: Writing parameter snapshot to 
/home/user/dlp/descent/p60.parameters_snapshot.0
PID6803 2017-02-27 14:00:39,727 Info:root: If this computation gets 
interrupted, it can be resumed with ./cado-nfs.py 
/home/user/dlp/descent/p60.parameters_snapshot.0
PID6803 2017-02-27 14:00:39,795 Debug:Client Launcher: Enter 
Task.__init__(slaves)
PID6803 2017-02-27 14:00:39,795 Debug:Client Launcher: state = {}
PID6803 2017-02-27 14:00:39,795 Debug:Client Launcher: self.parameters = 
MyParameters: name = None, prefix = ['slaves']
PID6803 2017-02-27 14:00:39,796 Debug:Client Launcher: params = {'hostnames': 
'localhost', 'scriptpath': '/home/user/cado/cado_build/bin', 'run': True, 
'nrclients': 5}
PID6803 2017-02-27 14:00:39,797 Debug:Client Launcher: Exit 
Task.__init__(slaves)
PID6803 2017-02-27 14:00:39,835 Info:Server Launcher: Adding 192.168.1.97 to 
whitelist to allow clients on localhost to connect
PID6803 2017-02-27 14:00:39,848 Debug:HTTP server: Found upload.py at 
$CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:00:39,854 Info:HTTP server: Using non-threaded HTTP 
server
PID6803 2017-02-27 14:00:39,855 Info:HTTP server: Using whitelist: 
localhost,192.168.1.97
PID6803 2017-02-27 14:00:39,872 Debug:Generate Factor Base: Enter 
Task.__init__(factorbase)
PID6803 2017-02-27 14:00:39,872 Debug:Generate Factor Base: state = {}
PID6803 2017-02-27 14:00:39,873 Debug:Generate Factor Base: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID6803 2017-02-27 14:00:39,873 Debug:Generate Factor Base: params = {'lim0': 
500000, 'gzip': True, 'workdir': '/home/user/dlp/descent', 'name': 'p60', 
'I': 11, 'run': True, 'lim1': 600000}
PID6803 2017-02-27 14:00:39,873 Debug:Generate Factor Base: Exit 
Task.__init__(factorbase)
PID6803 2017-02-27 14:00:39,884 Debug:Generate Free Relations: Enter 
Task.__init__(freerel)
PID6803 2017-02-27 14:00:39,884 Debug:Generate Free Relations: state = {}
PID6803 2017-02-27 14:00:39,885 Debug:Generate Free Relations: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'sieve']
PID6803 2017-02-27 14:00:39,885 Debug:Generate Free Relations: params = 
{'gzip': True, 'run': True, 'workdir': '/home/user/dlp/descent', 'name': 
'p60', 'dlp': True}
PID6803 2017-02-27 14:00:39,886 Debug:Generate Free Relations: Exit 
Task.__init__(freerel)
PID6803 2017-02-27 14:00:39,910 Debug:Lattice Sieving: Enter 
Task.__init__(sieving)
PID6803 2017-02-27 14:00:39,910 Debug:Lattice Sieving: state = {}
PID6803 2017-02-27 14:00:39,911 Debug:Lattice Sieving: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID6803 2017-02-27 14:00:39,911 Debug:Lattice Sieving: params = {'gzip': 
True, 'wutimeout': 10800, 'name': 'p60', 'lim1': 600000, 'run': True, 
'maxresubmit': 5, 'rels_wanted': 1000000, 'maxwu': 10, 'workdir': 
'/home/user/dlp/descent', 'maxfailed': 100, 'maxtimedout': 100, 'qrange': 
1000, 'qmin': 1}
PID6803 2017-02-27 14:00:39,912 Debug:Lattice Sieving: Exit 
Task.__init__(sieving)
PID6803 2017-02-27 14:00:39,972 Debug:Filtering - Duplicate Removal, 
splitting pass: Enter Task.__init__(duplicates1)
PID6803 2017-02-27 14:00:39,972 Debug:Filtering - Duplicate Removal, 
splitting pass: state = {}
PID6803 2017-02-27 14:00:39,973 Debug:Filtering - Duplicate Removal, 
splitting pass: self.parameters = MyParameters: name = None, prefix = 
['tasks', 'filter']
PID6803 2017-02-27 14:00:39,973 Debug:Filtering - Duplicate Removal, 
splitting pass: params = {'nslices_log': 1, 'workdir': 
'/home/user/dlp/descent', 'name': 'p60', 'run': True}
PID6803 2017-02-27 14:00:39,974 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Task.__init__(duplicates1)
PID6803 2017-02-27 14:00:40,017 Debug:Filtering - Duplicate Removal, removal 
pass: Enter Task.__init__(duplicates2)
PID6803 2017-02-27 14:00:40,017 Debug:Filtering - Duplicate Removal, removal 
pass: state = {}
PID6803 2017-02-27 14:00:40,017 Debug:Filtering - Duplicate Removal, removal 
pass: self.parameters = MyParameters: name = None, prefix = ['tasks', 
'filter']
PID6803 2017-02-27 14:00:40,017 Debug:Filtering - Duplicate Removal, removal 
pass: params = {'nslices_log': 1, 'workdir': '/home/user/dlp/descent', 
'name': 'p60', 'run': True, 'dlp': True}
PID6803 2017-02-27 14:00:40,018 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Task.__init__(duplicates2)
PID6803 2017-02-27 14:00:40,052 Debug:Filtering - Singleton removal: Enter 
Task.__init__(purge)
PID6803 2017-02-27 14:00:40,052 Debug:Filtering - Singleton removal: state = 
{}
PID6803 2017-02-27 14:00:40,052 Debug:Filtering - Singleton removal: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'filter']
PID6803 2017-02-27 14:00:40,052 Debug:Filtering - Singleton removal: params = 
{'gzip': True, 'workdir': '/home/user/dlp/descent', 'name': 'p60', 'galois': 
'none', 'required_excess': 0.0, 'add_ratio': 0.01, 'run': True, 'dlp': True}
PID6803 2017-02-27 14:00:40,053 Debug:Filtering - Singleton removal: Exit 
Task.__init__(purge)
PID6803 2017-02-27 14:00:40,089 Debug:Polynomial Selection (size optimized): 
Enter Task.__init__(polyselect1)
PID6803 2017-02-27 14:00:40,089 Debug:Polynomial Selection (size optimized): 
state = {}
PID6803 2017-02-27 14:00:40,090 Debug:Polynomial Selection (size optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID6803 2017-02-27 14:00:40,090 Debug:Polynomial Selection (size optimized): 
params = {'admin': 0, 'wutimeout': 10800, 'name': 'p60', 'run': True, 'lim1': 
600000, 'N': 314159265358979323846264338327950288419716939937510582145843, 
'maxresubmit': 5, 'lim0': 500000, 'maxwu': 10, 'workdir': 
'/home/user/dlp/descent', 'I': 11, 'admax': 1000, 'maxfailed': 100, 
'adrange': 500, 'nrkeep': 20, 'maxtimedout': 100}
PID6803 2017-02-27 14:00:40,090 Debug:Polynomial Selection (size optimized): 
Exit Task.__init__(polyselect1)
PID6803 2017-02-27 14:00:40,139 Debug:Polynomial Selection (root optimized): 
Enter Task.__init__(polyselect2)
PID6803 2017-02-27 14:00:40,139 Debug:Polynomial Selection (root optimized): 
state = {}
PID6803 2017-02-27 14:00:40,139 Debug:Polynomial Selection (root optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID6803 2017-02-27 14:00:40,139 Debug:Polynomial Selection (root optimized): 
params = {'wutimeout': 10800, 'name': 'p60', 'lim1': 600000, 'run': True, 
'N': 314159265358979323846264338327950288419716939937510582145843, 'lim0': 
500000, 'maxwu': 10, 'workdir': '/home/user/dlp/descent', 'I': 11, 
'maxfailed': 100, 'maxtimedout': 100, 'maxresubmit': 5}
PID6803 2017-02-27 14:00:40,140 Debug:Polynomial Selection (root optimized): 
Exit Task.__init__(polyselect2)
PID6803 2017-02-27 14:00:40,174 Debug:Number Theory for DLP: Enter 
Task.__init__(numbertheory)
PID6803 2017-02-27 14:00:40,174 Debug:Number Theory for DLP: state = {}
PID6803 2017-02-27 14:00:40,174 Debug:Number Theory for DLP: self.parameters 
= MyParameters: name = None, prefix = ['tasks']
PID6803 2017-02-27 14:00:40,175 Debug:Number Theory for DLP: params = {'run': 
True, 'workdir': '/home/user/dlp/descent', 'name': 'p60', 'nsm0': -1, 'nsm1': 
-1}
PID6803 2017-02-27 14:00:40,175 Debug:Number Theory for DLP: Exit 
Task.__init__(numbertheory)
PID6803 2017-02-27 14:00:40,187 Debug:Filtering - Galois: Enter 
Task.__init__(filtergalois)
PID6803 2017-02-27 14:00:40,187 Debug:Filtering - Galois: state = {}
PID6803 2017-02-27 14:00:40,187 Debug:Filtering - Galois: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID6803 2017-02-27 14:00:40,187 Debug:Filtering - Galois: params = {'run': 
True, 'workdir': '/home/user/dlp/descent', 'name': 'p60', 'galois': 'none'}
PID6803 2017-02-27 14:00:40,188 Debug:Filtering - Galois: Exit 
Task.__init__(filtergalois)
PID6803 2017-02-27 14:00:40,200 Debug:Schirokauer Maps: Enter 
Task.__init__(sm)
PID6803 2017-02-27 14:00:40,200 Debug:Schirokauer Maps: state = {}
PID6803 2017-02-27 14:00:40,200 Debug:Schirokauer Maps: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID6803 2017-02-27 14:00:40,201 Debug:Schirokauer Maps: params = {'run': 
True, 'workdir': '/home/user/dlp/descent', 'name': 'p60'}
PID6803 2017-02-27 14:00:40,201 Debug:Schirokauer Maps: Exit Task.__init__(sm)
PID6803 2017-02-27 14:00:40,212 Debug:Filtering - Merging: Enter 
Task.__init__(mergedlp)
PID6803 2017-02-27 14:00:40,212 Debug:Filtering - Merging: state = {}
PID6803 2017-02-27 14:00:40,212 Debug:Filtering - Merging: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID6803 2017-02-27 14:00:40,213 Debug:Filtering - Merging: params = {'gzip': 
True, 'run': True, 'workdir': '/home/user/dlp/descent', 'name': 'p60'}
PID6803 2017-02-27 14:00:40,213 Debug:Filtering - Merging: Exit 
Task.__init__(mergedlp)
PID6803 2017-02-27 14:00:40,225 Debug:Linear Algebra: Enter 
Task.__init__(linalgdlp)
PID6803 2017-02-27 14:00:40,226 Debug:Linear Algebra: state = {}
PID6803 2017-02-27 14:00:40,226 Debug:Linear Algebra: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'linalg']
PID6803 2017-02-27 14:00:40,226 Debug:Linear Algebra: params = {'workdir': 
'/home/user/dlp/descent', 'name': 'p60', 'run': True, 'n': 0, 'ell': 
157079632679489661923132169163975144209858469968755291072921, 'm': 0}
PID6803 2017-02-27 14:00:40,227 Debug:Linear Algebra: Exit 
Task.__init__(linalgdlp)
PID6803 2017-02-27 14:00:40,238 Debug:Logarithms Reconstruction: Enter 
Task.__init__(reconstructlog)
PID6803 2017-02-27 14:00:40,238 Debug:Logarithms Reconstruction: state = {}
PID6803 2017-02-27 14:00:40,238 Debug:Logarithms Reconstruction: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 
'reconstructlog']
PID6803 2017-02-27 14:00:40,238 Debug:Logarithms Reconstruction: params = 
{'partial': False, 'workdir': '/home/user/dlp/descent', 'name': 'p60', 'run': 
True, 'checkdlp': True}
PID6803 2017-02-27 14:00:40,239 Debug:Logarithms Reconstruction: Exit 
Task.__init__(reconstructlog)
PID6803 2017-02-27 14:00:40,251 Debug:Individual logarithm: Enter 
Task.__init__(descent)
PID6803 2017-02-27 14:00:40,251 Debug:Individual logarithm: state = {}
PID6803 2017-02-27 14:00:40,251 Debug:Individual logarithm: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'descent']
PID6803 2017-02-27 14:00:40,252 Debug:Individual logarithm: params = {'mfb0': 
44, 'init_mfb': 48, 'descent_hint': 
'/home/user/cado/cado_build/share/cado-nfs-2.2.1/dlp/p60.hint', 'name': 
'p60', 'mfb1': 44, 'init_ncurves': 10, 'init_tkewness': 200000, 'init_lim': 
100000, 'init_lpb': 24, 'target': 4, 'lim0': 500000, 'run': True, 'workdir': 
'/home/user/dlp/descent', 'lim1': 600000, 'lpb0': 22, 'I': 11, 'lpb1': 22, 
'execpath': '/home/user/cado/cado_build/lib/cado-nfs-2.2.1', 'init_I': 11}
PID6803 2017-02-27 14:00:40,252 Debug:Individual logarithm: Exit 
Task.__init__(descent)
PID6803 2017-02-27 14:00:40,253 Warning:Complete Factorization: Parameter 
tasks.linalg.bwc.thr = 4x2 was not used anywhere
PID6803 2017-02-27 14:00:40,253 Warning:Complete Factorization: Parameter 
tasks.sqrt.sqrt.threads = 8 was not used anywhere
PID6803 2017-02-27 14:00:40,254 Info:Complete Factorization: Computing 
Discrete Logs in 
GF(314159265358979323846264338327950288419716939937510582145843)
PID6803 2017-02-27 14:00:40,260 Info:HTTP server: serving at 
http://192.168.1.97:58191 (0.0.0.0)
PID6803 2017-02-27 14:00:40,260 Info:HTTP server: For debugging purposes, the 
URL above can be accessed if the server.only_registered=False parameter is 
added
PID6803 2017-02-27 14:00:40,260 Info:HTTP server: You can start additional 
cado-nfs-client.py scripts with parameters: --server=http://192.168.1.97:58191
PID6803 2017-02-27 14:00:40,261 Info:HTTP server: If you want to start 
additional clients, remember to add their hosts to server.whitelist
PID6803 2017-02-27 14:00:40,265 Info:Client Launcher: Starting client id 
localhost on host localhost
PID6803 2017-02-27 14:00:40,269 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:58191 --daemon 
--clientid localhost --basepath /home/user/dlp/descent/client
PID6803 2017-02-27 14:00:40,337 Debug:Command: Process with PID 6806 finished 
successfully
PID6803 2017-02-27 14:00:40,337 Debug:Command: Process with PID 6806 stdout: 
b'PID: 6807\n'
PID6803 2017-02-27 14:00:40,337 Debug:Command: Process with PID 6806 stderr: 
b'INFO:root:Starting client localhost\nINFO:root:Python version is 2.7.5\n'
PID6803 2017-02-27 14:00:40,344 Info:Client Launcher: Starting client id 
localhost+2 on host localhost
PID6803 2017-02-27 14:00:40,346 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:58191 --daemon 
--clientid localhost+2 --basepath /home/user/dlp/descent/client
PID6803 2017-02-27 14:00:40,409 Debug:Command: Process with PID 6808 finished 
successfully
PID6803 2017-02-27 14:00:40,410 Debug:Command: Process with PID 6808 stdout: 
b'PID: 6809\n'
PID6803 2017-02-27 14:00:40,410 Debug:Command: Process with PID 6808 stderr: 
b'INFO:root:Starting client localhost+2\nINFO:root:Python version is 2.7.5\n'
PID6803 2017-02-27 14:00:40,415 Info:Client Launcher: Starting client id 
localhost+3 on host localhost
PID6803 2017-02-27 14:00:40,417 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:58191 --daemon 
--clientid localhost+3 --basepath /home/user/dlp/descent/client
PID6803 2017-02-27 14:00:40,482 Debug:Command: Process with PID 6810 finished 
successfully
PID6803 2017-02-27 14:00:40,482 Debug:Command: Process with PID 6810 stdout: 
b'PID: 6811\n'
PID6803 2017-02-27 14:00:40,482 Debug:Command: Process with PID 6810 stderr: 
b'INFO:root:Starting client localhost+3\nINFO:root:Python version is 2.7.5\n'
PID6803 2017-02-27 14:00:40,791 Info:Client Launcher: Starting client id 
localhost+4 on host localhost
PID6803 2017-02-27 14:00:40,795 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:58191 --daemon 
--clientid localhost+4 --basepath /home/user/dlp/descent/client
PID6803 2017-02-27 14:00:40,882 Debug:Command: Process with PID 6818 finished 
successfully
PID6803 2017-02-27 14:00:40,882 Debug:Command: Process with PID 6818 stdout: 
b'PID: 6819\n'
PID6803 2017-02-27 14:00:40,882 Debug:Command: Process with PID 6818 stderr: 
b'INFO:root:Starting client localhost+4\nINFO:root:Python version is 2.7.5\n'
PID6803 2017-02-27 14:00:40,888 Info:Client Launcher: Starting client id 
localhost+5 on host localhost
PID6803 2017-02-27 14:00:40,890 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:58191 --daemon 
--clientid localhost+5 --basepath /home/user/dlp/descent/client
PID6803 2017-02-27 14:00:40,956 Debug:Command: Process with PID 6820 finished 
successfully
PID6803 2017-02-27 14:00:40,956 Debug:Command: Process with PID 6820 stdout: 
b'PID: 6821\n'
PID6803 2017-02-27 14:00:40,956 Debug:Command: Process with PID 6820 stderr: 
b'INFO:root:Starting client localhost+5\nINFO:root:Python version is 2.7.5\n'
PID6803 2017-02-27 14:00:40,962 Info:Client Launcher: Running clients: 
localhost+4 (Host localhost, PID 6819), localhost+3 (Host localhost, PID 
6811), localhost+2 (Host localhost, PID 6809), localhost+5 (Host localhost, 
PID 6821), localhost (Host localhost, PID 6807)
PID6803 2017-02-27 14:00:40,963 Info:Polynomial Selection (size optimized): 
Starting
PID6803 2017-02-27 14:00:40,963 Debug:Polynomial Selection (size optimized): 
polyselect1.run(): Task state: {'wu_timedout': 0, 'adnext': 0, 'wu_failed': 
0, 'wu_received': 0, 'start_real_time': 0, 'wu_submitted': 0, 
'start_achievement': -1}
PID6803 2017-02-27 14:00:40,963 Info:Polynomial Selection (size optimized): 0 
polynomials in queue from previous run
PID6803 2017-02-27 14:00:40,964 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID6803 2017-02-27 14:00:40,967 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect is 
('523ee132ce36187ad068440240d54a270c8e65f0', (155049494, 919857, 
1488182382.0))
PID6803 2017-02-27 14:00:40,968 Debug:Server Launcher: Registering file name 
polyselect with target $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID6803 2017-02-27 14:00:40,972 Info:Polynomial Selection (size optimized): 
Adding workunit p60_polyselect1_0-500 to database
PID6803 2017-02-27 14:00:40,983 Info:Polynomial Selection (size optimized): 
Adding workunit p60_polyselect1_500-1000 to database
PID6803 2017-02-27 14:00:40,990 Debug:Polynomial Selection (size optimized): 
Setting last timeout check to 1488193240.990524
PID6803 2017-02-27 14:00:50,365 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect1_0-500 to client localhost
PID6803 2017-02-27 14:00:50,366 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID6803 2017-02-27 14:00:50,369 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect to $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID6803 2017-02-27 14:00:50,370 Debug:HTTP server: 127.0.0.1 "GET /polyselect 
HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:00:50,434 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect1_500-1000 to client localhost+2
PID6803 2017-02-27 14:00:50,434 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID6803 2017-02-27 14:00:51,143 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:00:51,143 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:00:51,379 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:00:51,379 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:00:52,290 Info:Polynomial Selection (size optimized): 
Parsed 106 polynomials, added 42 to priority queue (has 20)
PID6803 2017-02-27 14:00:52,290 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 17.320000
PID6803 2017-02-27 14:00:52,312 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '1.74', 'stats_optlognorm': '106 
18.97 21.01 22.61 0.89', 'stats_collisions': '96.13', 'stats_rawlognorm': 
'106 19.15 22.0 27.57 1.49'}
PID6803 2017-02-27 14:00:52,313 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '1.74', 'stats_optlognorm': '106 18.97 
21.01 22.61 0.89', 'stats_collisions': '96.13', 'stats_rawlognorm': '106 
19.15 22.0 27.57 1.49'}
PID6803 2017-02-27 14:00:52,320 Info:Polynomial Selection (size optimized): 
Marking workunit p60_polyselect1_0-500 as ok (50.0% => ETA Unknown)
PID6803 2017-02-27 14:00:52,367 Info:Polynomial Selection (size optimized): 
Parsed 75 polynomials, added 5 to priority queue (has 20)
PID6803 2017-02-27 14:00:52,367 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 17.190000
PID6803 2017-02-27 14:00:52,375 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '1.3', 'stats_optlognorm': '75 
19.18 21.1 23.66 0.8', 'stats_collisions': '96.13', 'stats_rawlognorm': '75 
20.29 22.1 25.69 0.98'}
PID6803 2017-02-27 14:00:52,375 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '3.04', 'stats_optlognorm': '181 18.97 
21.04729281767956 23.66 0.8550092031447349', 'stats_collisions': '192.26', 
'stats_rawlognorm': '181 19.15 22.0414364640884 27.57 1.304051965119179'}
PID6803 2017-02-27 14:00:52,376 Info:Polynomial Selection (size optimized): 
Marking workunit p60_polyselect1_500-1000 as ok (100.0% => ETA Mon Feb 27 
14:00:52 2017)
PID6803 2017-02-27 14:00:52,382 Info:Polynomial Selection (size optimized): 
Finished
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 181/19.150/22.041/27.570/1.304
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 181/18.970/21.047/23.660/0.855
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (size optimized): 
Total time: 3.04
PID6803 2017-02-27 14:00:52,383 Info:Polynomial Selection (root optimized): 
Starting
PID6803 2017-02-27 14:00:52,384 Debug:Polynomial Selection (root optimized): 
polyselect2.run(): Task state: {'wu_timedout': 0, 'wu_failed': 0, 
'nr_poly_submitted': 0, 'wu_received': 0, 'start_real_time': 0, 
'wu_submitted': 0, 'start_achievement': -1}
PID6803 2017-02-27 14:00:52,384 Info:Polynomial Selection (root optimized): 
No polynomial was previously found
PID6803 2017-02-27 14:00:52,386 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_0
PID6803 2017-02-27 14:00:52,386 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_0 is 
('c1d5828ade28ca42666042dc544acad5b6563ac9', (75148181, 1797, 
1488193252.3845282))
PID6803 2017-02-27 14:00:52,386 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID6803 2017-02-27 14:00:52,388 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt is 
('1b358450bdfb6ff3f560a5a4c4ec1d6fa276c243', (155049495, 823886, 
1488182381.0))
PID6803 2017-02-27 14:00:52,388 Debug:Server Launcher: Registering file name 
polyselect_ropt with target 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID6803 2017-02-27 14:00:52,393 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_0 with target /home/user/dlp/descent/p60.polyselect2.raw_0
PID6803 2017-02-27 14:00:52,402 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_0 to database
PID6803 2017-02-27 14:00:52,413 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_6
PID6803 2017-02-27 14:00:52,413 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_6 is 
('d5896f3dd40bfa8ec3ddcca827a492aea3ab0934', (75148207, 1785, 
1488193252.411528))
PID6803 2017-02-27 14:00:52,413 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_6 with target /home/user/dlp/descent/p60.polyselect2.raw_6
PID6803 2017-02-27 14:00:52,419 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_6 to database
PID6803 2017-02-27 14:00:52,425 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_12
PID6803 2017-02-27 14:00:52,425 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_12 is 
('e04c3cbfe97dcddbd433262336bdb9dd2cc56938', (76079796, 1788, 
1488193252.4235282))
PID6803 2017-02-27 14:00:52,426 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_12 with target 
/home/user/dlp/descent/p60.polyselect2.raw_12
PID6803 2017-02-27 14:00:52,430 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_12 to database
PID6803 2017-02-27 14:00:52,439 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_18
PID6803 2017-02-27 14:00:52,439 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.polyselect2.raw_18 is 
('f15c344913a60c1cf834d1aa3f9909f178a04b94', (76079807, 589, 
1488193252.4375281))
PID6803 2017-02-27 14:00:52,439 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_18 with target 
/home/user/dlp/descent/p60.polyselect2.raw_18
PID6803 2017-02-27 14:00:52,443 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_18 to database
PID6803 2017-02-27 14:00:52,448 Debug:Polynomial Selection (root optimized): 
Setting last timeout check to 1488193252.448351
PID6803 2017-02-27 14:01:00,908 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_0 to client localhost+3
PID6803 2017-02-27 14:01:00,908 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:00,911 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_0 to /home/user/dlp/descent/p60.polyselect2.raw_0
PID6803 2017-02-27 14:01:00,912 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_0 HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:00,915 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect_ropt to $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID6803 2017-02-27 14:01:00,915 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect_ropt HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:00,945 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_6 to client localhost+4
PID6803 2017-02-27 14:01:00,946 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:00,953 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_6 to /home/user/dlp/descent/p60.polyselect2.raw_6
PID6803 2017-02-27 14:01:00,953 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_6 HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:01,009 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_12 to client localhost+5
PID6803 2017-02-27 14:01:01,009 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:01,011 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_12 to /home/user/dlp/descent/p60.polyselect2.raw_12
PID6803 2017-02-27 14:01:01,011 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_12 HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:01,197 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_18 to client localhost+2
PID6803 2017-02-27 14:01:01,197 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:01,199 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_18 to /home/user/dlp/descent/p60.polyselect2.raw_18
PID6803 2017-02-27 14:01:01,200 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_18 HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:06,133 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:06,134 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:01:06,488 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/descent/p60.upload/p60.polyselect2.19fhfzk_.opt_18: Murphy E = 
2.35e-06
PID6803 2017-02-27 14:01:06,488 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 314159265358979323846264338327950288419716939937510582145843
skew: 2609.102
c0: 28714187096082117
c1: -81173262014511
c2: -18556976378
c3: 11732750
c4: 600
Y0: 96073988789362
Y1: 51214779733
# MurphyE (Bf=6.00e+05,Bg=5.00e+05,area=1.26e+12) = 2.35e-06
# f(x) = 
600*x^4+11732750*x^3-18556976378*x^2-81173262014511*x+28714187096082117
# g(x) = 51214779733*x+96073988789362

PID6803 2017-02-27 14:01:06,490 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '8.96', 'stats_rootsieve_time': 
'8.48'}
PID6803 2017-02-27 14:01:06,490 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '8.96', 'stats_rootsieve_time': '8.48'}
PID6803 2017-02-27 14:01:06,505 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_18 as ok (10.0% => ETA Unknown)
PID6803 2017-02-27 14:01:11,017 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:11,018 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:01:11,545 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/descent/p60.upload/p60.polyselect2.nrj1309n.opt_0 with 
E=2.26e-06 is no better than current best with E=2.35e-06
PID6803 2017-02-27 14:01:11,545 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '19.3', 'stats_rootsieve_time': 
'18.15'}
PID6803 2017-02-27 14:01:11,546 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '28.26', 'stats_rootsieve_time': '26.63'}
PID6803 2017-02-27 14:01:11,546 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_0 as ok (40.0% => ETA Mon Feb 27 14:01:49 
2017)
PID6803 2017-02-27 14:01:11,635 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:11,635 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:01:12,419 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:12,419 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:01:12,555 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/descent/p60.upload/p60.polyselect2.g4abrev8.opt_6 with 
E=2.33e-06 is no better than current best with E=2.35e-06
PID6803 2017-02-27 14:01:12,557 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '19.85', 'stats_rootsieve_time': 
'18.55'}
PID6803 2017-02-27 14:01:12,557 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '48.11', 'stats_rootsieve_time': '45.18'}
PID6803 2017-02-27 14:01:12,558 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_6 as ok (70.0% => ETA Mon Feb 27 14:01:22 
2017)
PID6803 2017-02-27 14:01:12,567 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/descent/p60.upload/p60.polyselect2.8n66oz9c.opt_12: Murphy E = 
2.67e-06
PID6803 2017-02-27 14:01:12,567 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 314159265358979323846264338327950288419716939937510582145843
skew: 4826.582
c0: 1874158438742675
c1: 20428056679672
c2: -1499008127
c3: -276532
c4: 120
Y0: -237731156825892
Y1: 20070872717
# MurphyE (Bf=6.00e+05,Bg=5.00e+05,area=1.26e+12) = 2.67e-06
# f(x) = 120*x^4-276532*x^3-1499008127*x^2+20428056679672*x+1874158438742675
# g(x) = 20070872717*x-237731156825892

PID6803 2017-02-27 14:01:12,569 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '22.03', 'stats_rootsieve_time': 
'20.89'}
PID6803 2017-02-27 14:01:12,569 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '70.14', 'stats_rootsieve_time': '66.07'}
PID6803 2017-02-27 14:01:12,571 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_12 as ok (100.0% => ETA Mon Feb 27 14:01:12 
2017)
PID6803 2017-02-27 14:01:12,577 Info:Polynomial Selection (root optimized): 
Finished, best polynomial from file 
/home/user/dlp/descent/p60.upload/p60.polyselect2.8n66oz9c.opt_12 has 
Murphy_E = 2.67e-06
PID6803 2017-02-27 14:01:12,578 Info:Polynomial Selection (root optimized): 
Best overall polynomial was 13-th in list after size optimization
PID6803 2017-02-27 14:01:12,585 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID6803 2017-02-27 14:01:12,585 Info:Polynomial Selection (root optimized): 
Total time: 70.14
PID6803 2017-02-27 14:01:12,586 Info:Polynomial Selection (root optimized): 
Rootsieve time: 66.07
PID6803 2017-02-27 14:01:12,586 Info:Number Theory for DLP: Starting
PID6803 2017-02-27 14:01:12,586 Debug:Number Theory for DLP: 
numbertheory.run(): Task state: {}
PID6803 2017-02-27 14:01:12,601 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/badideals -poly /home/user/dlp/descent/p60.poly 
-ell 157079632679489661923132169163975144209858469968755291072921 
-badidealinfo /home/user/dlp/descent/p60.badidealinfo -badideals 
/home/user/dlp/descent/p60.badideals > 
/home/user/dlp/descent/p60.badideals.stdout.1 2> 
/home/user/dlp/descent/p60.badideals.stderr.1
PID6803 2017-02-27 14:01:12,714 Debug:Command: Process with PID 6879 finished 
successfully
PID6803 2017-02-27 14:01:12,722 Debug:Number Theory for DLP: Exit 
NumberTheoryTask.run(numbertheory)
PID6803 2017-02-27 14:01:12,723 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.11/0.113048
PID6803 2017-02-27 14:01:12,723 Info:Generate Factor Base: Starting
PID6803 2017-02-27 14:01:12,723 Debug:Generate Factor Base: factorbase.run(): 
Task state: {}
PID6803 2017-02-27 14:01:12,735 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/makefb -poly 
/home/user/dlp/descent/p60.poly -lim 600000 -maxbits 11 -out 
/home/user/dlp/descent/p60.roots.gz -t 10 > 
/home/user/dlp/descent/p60.makefb.stdout.1 2> 
/home/user/dlp/descent/p60.makefb.stderr.1
PID6803 2017-02-27 14:01:13,114 Debug:Command: Process with PID 6880 finished 
successfully
PID6803 2017-02-27 14:01:13,121 Info:Generate Factor Base: Finished
PID6803 2017-02-27 14:01:13,121 Info:Generate Factor Base: Total cpu/real 
time for makefb: 1.89/0.379226
PID6803 2017-02-27 14:01:13,122 Info:Generate Free Relations: Starting
PID6803 2017-02-27 14:01:13,122 Debug:Generate Free Relations: freerel.run(): 
Task state: {}
PID6803 2017-02-27 14:01:13,133 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/freerel -poly 
/home/user/dlp/descent/p60.poly -renumber 
/home/user/dlp/descent/p60.renumber.gz -lpb0 22 -lpb1 22 -out 
/home/user/dlp/descent/p60.freerel.gz -badideals 
/home/user/dlp/descent/p60.badideals -lcideals -t 10 > 
/home/user/dlp/descent/p60.freerel.stdout.1 2> 
/home/user/dlp/descent/p60.freerel.stderr.1
PID6803 2017-02-27 14:01:15,295 Debug:Command: Process with PID 6931 finished 
successfully
PID6803 2017-02-27 14:01:15,312 Info:Generate Free Relations: Found 12307 
free relations
PID6803 2017-02-27 14:01:15,312 Info:Generate Free Relations: Finished
PID6803 2017-02-27 14:01:15,312 Info:Generate Free Relations: Total cpu/real 
time for freerel: 16.22/2.16185
PID6803 2017-02-27 14:01:15,312 Info:Lattice Sieving: Starting
PID6803 2017-02-27 14:01:15,313 Debug:Lattice Sieving: sieving.run(): Task 
state: {'rels_wanted': 1000000, 'rels_found': 0, 'wu_timedout': 0, 'qnext': 
1, 'wu_failed': 0, 'wu_received': 0, 'start_real_time': 0, 'wu_submitted': 0, 
'start_achievement': -1}
PID6803 2017-02-27 14:01:15,314 Info:Lattice Sieving: We want 1000000 
relations
PID6803 2017-02-27 14:01:15,315 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.roots.gz
PID6803 2017-02-27 14:01:15,316 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.roots.gz is 
('180b08f641c9ff24d471d7cd1dd7f3f6173b2886', (77271113, 262693, 
1488193273.1125283))
PID6803 2017-02-27 14:01:15,316 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/descent/p60.poly
PID6803 2017-02-27 14:01:15,316 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/descent/p60.poly is 
('57b857bed68213ef2dd8d1aa8ddd13fc230f6b8b', (75148217, 369, 
1488193272.5785282))
PID6803 2017-02-27 14:01:15,317 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID6803 2017-02-27 14:01:15,333 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/las is 
('b5e4c2cf4e9948b30ac22d8db2f62260a17d93ab', (205121301, 6622258, 
1488182379.0))
PID6803 2017-02-27 14:01:15,334 Debug:Server Launcher: Registering file name 
las with target $CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID6803 2017-02-27 14:01:15,340 Debug:Server Launcher: Registering file name 
p60.roots.gz with target /home/user/dlp/descent/p60.roots.gz
PID6803 2017-02-27 14:01:15,344 Debug:Server Launcher: Registering file name 
p60.poly with target /home/user/dlp/descent/p60.poly
PID6803 2017-02-27 14:01:15,351 Info:Lattice Sieving: Adding workunit 
p60_sieving_1-1000 to database
PID6803 2017-02-27 14:01:15,367 Info:Lattice Sieving: Adding workunit 
p60_sieving_1000-2000 to database
PID6803 2017-02-27 14:01:15,374 Info:Lattice Sieving: Adding workunit 
p60_sieving_2000-3000 to database
PID6803 2017-02-27 14:01:15,387 Info:Lattice Sieving: Adding workunit 
p60_sieving_3000-4000 to database
PID6803 2017-02-27 14:01:15,394 Info:Lattice Sieving: Adding workunit 
p60_sieving_4000-5000 to database
PID6803 2017-02-27 14:01:15,401 Info:Lattice Sieving: Adding workunit 
p60_sieving_5000-6000 to database
PID6803 2017-02-27 14:01:15,408 Info:Lattice Sieving: Adding workunit 
p60_sieving_6000-7000 to database
PID6803 2017-02-27 14:01:15,421 Info:Lattice Sieving: Adding workunit 
p60_sieving_7000-8000 to database
PID6803 2017-02-27 14:01:15,430 Info:Lattice Sieving: Adding workunit 
p60_sieving_8000-9000 to database
PID6803 2017-02-27 14:01:15,440 Info:Lattice Sieving: Adding workunit 
p60_sieving_9000-10000 to database
PID6803 2017-02-27 14:01:15,448 Debug:Lattice Sieving: Setting last timeout 
check to 1488193275.448077
PID6803 2017-02-27 14:01:16,204 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_1-1000 to client localhost+2
PID6803 2017-02-27 14:01:16,204 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:16,206 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.roots.gz to /home/user/dlp/descent/p60.roots.gz
PID6803 2017-02-27 14:01:16,207 Debug:HTTP server: 127.0.0.1 "GET 
/p60.roots.gz HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:16,210 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.poly to /home/user/dlp/descent/p60.poly
PID6803 2017-02-27 14:01:16,211 Debug:HTTP server: 127.0.0.1 "GET /p60.poly 
HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:16,214 Debug:HTTP server: 127.0.0.1 Translated path 
/las to $CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID6803 2017-02-27 14:01:16,215 Debug:HTTP server: 127.0.0.1 "GET /las 
HTTP/1.1" HTTPStatus.OK -
PID6803 2017-02-27 14:01:16,450 Info:Lattice Sieving: Adding workunit 
p60_sieving_10000-11000 to database
PID6803 2017-02-27 14:01:21,047 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_1000-2000 to client localhost+3
PID6803 2017-02-27 14:01:21,047 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:21,455 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_2000-3000 to client localhost
PID6803 2017-02-27 14:01:21,455 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:21,473 Info:Lattice Sieving: Adding workunit 
p60_sieving_11000-12000 to database
PID6803 2017-02-27 14:01:21,504 Info:Lattice Sieving: Adding workunit 
p60_sieving_12000-13000 to database
PID6803 2017-02-27 14:01:21,699 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_3000-4000 to client localhost+4
PID6803 2017-02-27 14:01:21,699 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:22,484 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_4000-5000 to client localhost+5
PID6803 2017-02-27 14:01:22,488 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID6803 2017-02-27 14:01:22,538 Info:Lattice Sieving: Adding workunit 
p60_sieving_13000-14000 to database
PID6803 2017-02-27 14:01:22,603 Info:Lattice Sieving: Adding workunit 
p60_sieving_14000-15000 to database
PID6803 2017-02-27 14:02:32,567 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:32,570 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:02:33,564 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_5000-6000 to client localhost+2
PID6803 2017-02-27 14:02:33,564 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:33,566 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:33,567 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:02:34,037 Info:Lattice Sieving: Adding workunit 
p60_sieving_15000-16000 to database
PID6803 2017-02-27 14:02:34,105 Debug:Lattice Sieving: stderr is: b"# Average 
J=729 for 161 special-q's, max bucket fill 0.606306\n# Discarded 11 
special-q's out of 172 pushed\n# Total cpu time 74.03s [norm 0.04+1.0, 
sieving 35.8 (27.7 + 2.2 + 5.9), factor 37.2 (35.8 + 1.3)]\n# Total elapsed 
time 75.28s, per special-q 0.467559s, per relation 0.00023885s\n# 
PeakMemusage (MB) = 415 \n# Total 315164 reports [0.000235s/r, 1957.5r/sq]\n"
PID6803 2017-02-27 14:02:34,109 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.606306', 'stats_avg_J': '729.0 161', 
'stats_total_cpu_time': '74.03'}
PID6803 2017-02-27 14:02:34,109 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.606306', 'stats_avg_J': '729.0 161', 
'stats_total_cpu_time': '74.03'}
PID6803 2017-02-27 14:02:34,111 Info:Lattice Sieving: Found 315164 relations 
in '/home/user/dlp/descent/p60.upload/p60.1-1000.bzpgi94y.gz', total is now 
315164/1000000
PID6803 2017-02-27 14:02:34,143 Info:Lattice Sieving: Marking workunit 
p60_sieving_1-1000 as ok (31.5% => ETA Unknown)
PID6803 2017-02-27 14:02:34,275 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_6000-7000 to client localhost+4
PID6803 2017-02-27 14:02:34,275 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:35,181 Info:Lattice Sieving: Adding workunit 
p60_sieving_16000-17000 to database
PID6803 2017-02-27 14:02:35,219 Debug:Lattice Sieving: stderr is: b"# Average 
J=1024 for 113 special-q's, max bucket fill 0.605560\n# Discarded 0 
special-q's out of 113 pushed\n# Total cpu time 73.68s [norm 0.07+1.5, 
sieving 37.4 (28.7 + 2.4 + 6.3), factor 34.7 (33.6 + 1.1)]\n# Total elapsed 
time 71.26s, per special-q 0.630606s, per relation 0.000327276s\n# 
PeakMemusage (MB) = 414 \n# Total 217732 reports [0.000338s/r, 1926.8r/sq]\n"
PID6803 2017-02-27 14:02:35,230 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.60556', 'stats_avg_J': '1024.0 113', 
'stats_total_cpu_time': '73.68'}
PID6803 2017-02-27 14:02:35,230 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.606306', 'stats_avg_J': '850.6605839416059 274', 
'stats_total_cpu_time': '147.71'}
PID6803 2017-02-27 14:02:35,233 Info:Lattice Sieving: Found 217732 relations 
in '/home/user/dlp/descent/p60.upload/p60.3000-4000.s4p1jnas.gz', total is 
now 532896/1000000
PID6803 2017-02-27 14:02:35,236 Info:Lattice Sieving: Marking workunit 
p60_sieving_3000-4000 as ok (53.3% => ETA Mon Feb 27 14:05:26 2017)
PID6803 2017-02-27 14:02:43,857 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:43,859 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:02:44,702 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:44,703 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:02:45,130 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_7000-8000 to client localhost+5
PID6803 2017-02-27 14:02:45,131 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:45,141 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_8000-9000 to client localhost
PID6803 2017-02-27 14:02:45,141 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:45,334 Info:Lattice Sieving: Adding workunit 
p60_sieving_17000-18000 to database
PID6803 2017-02-27 14:02:45,372 Info:Lattice Sieving: Adding workunit 
p60_sieving_18000-19000 to database
PID6803 2017-02-27 14:02:45,404 Debug:Lattice Sieving: stderr is: b"# Average 
J=1024 for 129 special-q's, max bucket fill 0.640261\n# Discarded 0 
special-q's out of 129 pushed\n# Total cpu time 84.56s [norm 0.04+1.6, 
sieving 42.4 (32.5 + 2.9 + 7.0), factor 40.5 (39.2 + 1.4)]\n# Total elapsed 
time 82.29s, per special-q 0.637876s, per relation 0.000314496s\n# 
PeakMemusage (MB) = 414 \n# Total 261644 reports [0.000323s/r, 2028.2r/sq]\n"
PID6803 2017-02-27 14:02:45,410 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.640261', 'stats_avg_J': '1024.0 129', 
'stats_total_cpu_time': '84.56'}
PID6803 2017-02-27 14:02:45,410 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.640261', 'stats_avg_J': '906.1464019851117 403', 
'stats_total_cpu_time': '232.27'}
PID6803 2017-02-27 14:02:45,410 Info:Lattice Sieving: Found 261644 relations 
in '/home/user/dlp/descent/p60.upload/p60.2000-3000.yzjzv4pi.gz', total is 
now 794540/1000000
PID6803 2017-02-27 14:02:45,412 Info:Lattice Sieving: Marking workunit 
p60_sieving_2000-3000 as ok (79.5% => ETA Mon Feb 27 14:03:24 2017)
PID6803 2017-02-27 14:02:45,444 Debug:Lattice Sieving: stderr is: b"# Average 
J=1024 for 131 special-q's, max bucket fill 0.608979\n# Discarded 0 
special-q's out of 131 pushed\n# Total cpu time 84.36s [norm 0.09+1.8, 
sieving 43.0 (33.0 + 2.9 + 7.1), factor 39.4 (38.1 + 1.3)]\n# Total elapsed 
time 80.52s, per special-q 0.614664s, per relation 0.00033321s\n# 
PeakMemusage (MB) = 417 \n# Total 241652 reports [0.000349s/r, 1844.7r/sq]\n"
PID6803 2017-02-27 14:02:45,446 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.608979', 'stats_avg_J': '1024.0 131', 
'stats_total_cpu_time': '84.36'}
PID6803 2017-02-27 14:02:45,448 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.640261', 'stats_avg_J': '935.058052434457 534', 
'stats_total_cpu_time': '316.63'}
PID6803 2017-02-27 14:02:45,450 Info:Lattice Sieving: Found 241652 relations 
in '/home/user/dlp/descent/p60.upload/p60.4000-5000.s5r24yl3.gz', total is 
now 1036192/1000000
PID6803 2017-02-27 14:02:45,450 Info:Lattice Sieving: Marking workunit 
p60_sieving_4000-5000 as ok (103.6% => ETA Mon Feb 27 14:02:40 2017)
PID6803 2017-02-27 14:02:58,920 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID6803 2017-02-27 14:02:58,922 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID6803 2017-02-27 14:03:00,175 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_9000-10000 to client localhost+3
PID6803 2017-02-27 14:03:00,175 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID6803 2017-02-27 14:03:00,591 Info:Lattice Sieving: Adding workunit 
p60_sieving_19000-20000 to database
PID6803 2017-02-27 14:03:00,640 Info:Lattice Sieving: Reached target of 
1000000 relations, now have 1036192
PID6803 2017-02-27 14:03:00,640 Debug:Lattice Sieving: Exit 
SievingTask.run(sieving)
PID6803 2017-02-27 14:03:00,644 Info:Lattice Sieving: Aggregate statistics:
PID6803 2017-02-27 14:03:00,644 Info:Lattice Sieving: Total number of 
relations: 1036192
PID6803 2017-02-27 14:03:00,644 Info:Lattice Sieving: Average J: 935.058 for 
534 special-q, max bucket fill: 0.640261
PID6803 2017-02-27 14:03:00,646 Info:Lattice Sieving: Total CPU time: 316.63s
PID6803 2017-02-27 14:03:00,646 Info:Filtering - Duplicate Removal, splitting 
pass: Starting
PID6803 2017-02-27 14:03:00,646 Debug:Filtering - Duplicate Removal, 
splitting pass: duplicates1.run(): Task state: {}
PID6803 2017-02-27 14:03:00,648 Debug:Filtering - Duplicate Removal, 
splitting pass: new files to split are: 
['/home/user/dlp/descent/p60.upload/p60.2000-3000.yzjzv4pi.gz', 
'/home/user/dlp/descent/p60.upload/p60.1-1000.bzpgi94y.gz', 
'/home/user/dlp/descent/p60.upload/p60.3000-4000.s4p1jnas.gz', 
'/home/user/dlp/descent/p60.upload/p60.4000-5000.s5r24yl3.gz']
PID6803 2017-02-27 14:03:00,648 Info:Filtering - Duplicate Removal, splitting 
pass: Splitting 4 new files
PID6803 2017-02-27 14:03:00,725 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup1 -prefix dup1.0 -out 
/home/user/dlp/descent/p60.dup1/ 
/home/user/dlp/descent/p60.upload/p60.2000-3000.yzjzv4pi.gz 
/home/user/dlp/descent/p60.upload/p60.1-1000.bzpgi94y.gz 
/home/user/dlp/descent/p60.upload/p60.3000-4000.s4p1jnas.gz 
/home/user/dlp/descent/p60.upload/p60.4000-5000.s5r24yl3.gz > 
/home/user/dlp/descent/p60.dup1.stdout.1 2> 
/home/user/dlp/descent/p60.dup1.stderr.1
PID6803 2017-02-27 14:03:05,994 Debug:Command: Process with PID 11220 
finished successfully
PID6803 2017-02-27 14:03:06,019 Debug:Filtering - Duplicate Removal, 
splitting pass: Output file names: 
{'/home/user/dlp/descent/p60.dup1//0/dup1.0.0000.gz': 0, 
'/home/user/dlp/descent/p60.dup1//1/dup1.0.0000.gz': 1}
PID6803 2017-02-27 14:03:06,024 Debug:Filtering - Duplicate Removal, 
splitting pass: Newly arrived stats: {'stats_dup1_time': '5.0'}
PID6803 2017-02-27 14:03:06,024 Debug:Filtering - Duplicate Removal, 
splitting pass: Combined stats: {'stats_dup1_time': '5.0'}
PID6803 2017-02-27 14:03:06,050 Info:Filtering - Duplicate Removal, splitting 
pass: Relations per slice: 0: 517287, 1: 518905
PID6803 2017-02-27 14:03:06,050 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Duplicates1Task.run(duplicates1)
PID6803 2017-02-27 14:03:06,051 Info:Filtering - Duplicate Removal, splitting 
pass: Total cpu/real time for dup1: 4.56/5.26723
PID6803 2017-02-27 14:03:06,051 Info:Filtering - Duplicate Removal, splitting 
pass: Aggregate statistics:
PID6803 2017-02-27 14:03:06,051 Info:Filtering - Duplicate Removal, splitting 
pass: CPU time for dup1: 5.0s
PID6803 2017-02-27 14:03:06,051 Info:Filtering - Duplicate Removal, removal 
pass: Starting
PID6803 2017-02-27 14:03:06,051 Debug:Filtering - Duplicate Removal, removal 
pass: duplicates2.run(): Task state: {}
PID6803 2017-02-27 14:03:06,188 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup2 -nrels 517287 -renumber 
/home/user/dlp/descent/p60.renumber.gz -badidealinfo 
/home/user/dlp/descent/p60.badidealinfo -dl 
/home/user/dlp/descent/p60.dup1//0/dup1.0.0000.gz > 
/home/user/dlp/descent/p60.dup2.slice0.stdout.1 2> 
/home/user/dlp/descent/p60.dup2.slice0.stderr.1
PID6803 2017-02-27 14:03:10,772 Debug:Command: Process with PID 11449 
finished successfully
PID6803 2017-02-27 14:03:10,809 Info:Filtering - Duplicate Removal, removal 
pass: 382894 unique relations remain on slice 0
PID6803 2017-02-27 14:03:10,910 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup2 -nrels 518905 -renumber 
/home/user/dlp/descent/p60.renumber.gz -badidealinfo 
/home/user/dlp/descent/p60.badidealinfo -dl 
/home/user/dlp/descent/p60.dup1//1/dup1.0.0000.gz > 
/home/user/dlp/descent/p60.dup2.slice1.stdout.1 2> 
/home/user/dlp/descent/p60.dup2.slice1.stderr.1
PID6803 2017-02-27 14:03:15,756 Debug:Command: Process with PID 11643 
finished successfully
PID6803 2017-02-27 14:03:15,878 Info:Filtering - Duplicate Removal, removal 
pass: 383554 unique relations remain on slice 1
PID6803 2017-02-27 14:03:15,919 Info:Filtering - Duplicate Removal, removal 
pass: Of 1036192 newly added relations 766448 were unique (ratio 0.739678)
PID6803 2017-02-27 14:03:15,953 Info:Filtering - Duplicate Removal, removal 
pass: 766448 unique relations remain in total
PID6803 2017-02-27 14:03:15,955 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Duplicates2Task.run(duplicates2)
PID6803 2017-02-27 14:03:15,955 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 17.99/9.42945
PID6803 2017-02-27 14:03:15,956 Info:Filtering - Singleton removal: Starting
PID6803 2017-02-27 14:03:15,956 Debug:Filtering - Singleton removal: 
purge.run(): Task state: {'input_nrels': 0}
PID6803 2017-02-27 14:03:16,009 Info:Filtering - Singleton removal: Reading 
766448 unique and 12307 free relations, total 778755
PID6803 2017-02-27 14:03:16,075 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/purge -out 
/home/user/dlp/descent/p60.purged.gz -nrels 778755 -outdel 
/home/user/dlp/descent/p60.relsdel.gz -keep 2 -col-min-index 14797 
-col-max-index 591911 -t 10 /home/user/dlp/descent/p60.dup1//0/dup1.0.0000.gz 
/home/user/dlp/descent/p60.dup1//1/dup1.0.0000.gz 
/home/user/dlp/descent/p60.freerel.gz > 
/home/user/dlp/descent/p60.purge.stdout.1 2> 
/home/user/dlp/descent/p60.purge.stderr.1
PID6803 2017-02-27 14:03:33,318 Debug:Command: Process with PID 11842 
finished successfully
PID6803 2017-02-27 14:03:33,344 Info:Filtering - Singleton removal: After 
purge, 23139 relations with 23137 primes remain with weight 474229 and excess 
2
PID6803 2017-02-27 14:03:33,364 Info:Filtering - Singleton removal: Have 
enough relations
PID6803 2017-02-27 14:03:33,365 Info:HTTP server: Got notification to stop 
serving Workunits
PID6803 2017-02-27 14:03:33,366 Info:Lattice Sieving: Cancelling remaining 
workunits
PID6803 2017-02-27 14:03:33,395 Command:Command: /bin/kill 6819
PID6803 2017-02-27 14:03:33,397 Debug:Command: Process with PID 15612 
finished successfully
PID6803 2017-02-27 14:03:33,397 Info:Client Launcher: Stopped client 
localhost+4 (Host localhost, PID 6819)
PID6803 2017-02-27 14:03:33,420 Command:Command: /bin/kill 6811
PID6803 2017-02-27 14:03:33,421 Debug:Command: Process with PID 15613 
finished successfully
PID6803 2017-02-27 14:03:33,421 Info:Client Launcher: Stopped client 
localhost+3 (Host localhost, PID 6811)
PID6803 2017-02-27 14:03:33,443 Command:Command: /bin/kill 6809
PID6803 2017-02-27 14:03:33,445 Debug:Command: Process with PID 15617 
finished successfully
PID6803 2017-02-27 14:03:33,445 Info:Client Launcher: Stopped client 
localhost+2 (Host localhost, PID 6809)
PID6803 2017-02-27 14:03:33,469 Command:Command: /bin/kill 6821
PID6803 2017-02-27 14:03:33,474 Debug:Command: Process with PID 15618 
finished successfully
PID6803 2017-02-27 14:03:33,474 Info:Client Launcher: Stopped client 
localhost+5 (Host localhost, PID 6821)
PID6803 2017-02-27 14:03:33,499 Command:Command: /bin/kill 6807
PID6803 2017-02-27 14:03:33,499 Debug:Command: Process with PID 15619 
finished successfully
PID6803 2017-02-27 14:03:33,499 Info:Client Launcher: Stopped client 
localhost (Host localhost, PID 6807)
PID6803 2017-02-27 14:03:33,507 Debug:Filtering - Singleton removal: Exit 
PurgeTask.run(purge)
PID6803 2017-02-27 14:03:33,507 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 32.91/17.243
PID6803 2017-02-27 14:03:33,507 Info:Filtering - Merging: Starting
PID6803 2017-02-27 14:03:33,507 Debug:Filtering - Merging: mergedlp.run(): 
Task state: {}
PID6803 2017-02-27 14:03:33,515 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/merge-dl -mat 
/home/user/dlp/descent/p60.purged.gz -out 
/home/user/dlp/descent/p60.history.gz -maxlevel 15 -keep 2 -skip 0 
-target_density 100.0 -t 10 > /home/user/dlp/descent/p60.merge-dl.stdout.1 2> 
/home/user/dlp/descent/p60.merge-dl.stderr.1
PID6803 2017-02-27 14:03:35,154 Debug:Command: Process with PID 15620 
finished successfully
PID6803 2017-02-27 14:03:35,159 Info:Filtering - Merging: Merged matrix has 
5354 rows and total weight 535473 (100.0 entries per row on average)
PID6803 2017-02-27 14:03:35,165 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/replay-dl -purged 
/home/user/dlp/descent/p60.purged.gz -ideals /home/user/dlp/descent/p60.ideal 
-his /home/user/dlp/descent/p60.history.gz -index 
/home/user/dlp/descent/p60.index.gz -out 
/home/user/dlp/descent/p60.sparse.bin > 
/home/user/dlp/descent/p60.replay.stdout.2 2> 
/home/user/dlp/descent/p60.replay.stderr.2
PID6803 2017-02-27 14:03:35,412 Debug:Command: Process with PID 15642 
finished successfully
PID6803 2017-02-27 14:03:35,421 Debug:Filtering - Merging: Exit 
MergeDLPTask.run(mergedlp)
PID6803 2017-02-27 14:03:35,421 Info:Filtering - Merging: Total cpu/real time 
for merge-dl: 3.19/1.63952
PID6803 2017-02-27 14:03:35,421 Info:Filtering - Merging: Total cpu/real time 
for replay-dl: 0.21/0.246538
PID6803 2017-02-27 14:03:35,421 Info:Schirokauer Maps: Starting
PID6803 2017-02-27 14:03:35,421 Debug:Schirokauer Maps: sm.run(): Task state: 
{}
PID6803 2017-02-27 14:03:35,429 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/sm -poly /home/user/dlp/descent/p60.poly 
-purged /home/user/dlp/descent/p60.purged.gz -index 
/home/user/dlp/descent/p60.index.gz -out /home/user/dlp/descent/p60.sm -ell 
157079632679489661923132169163975144209858469968755291072921 -nsm '0,2' -t 10 
> /home/user/dlp/descent/p60.sm.stdout.1 2> 
/home/user/dlp/descent/p60.sm.stderr.1
PID6803 2017-02-27 14:03:47,324 Debug:Command: Process with PID 15653 
finished successfully
PID6803 2017-02-27 14:03:47,417 Debug:Schirokauer Maps: Exit SMTask.run(sm)
PID6803 2017-02-27 14:03:47,417 Info:Schirokauer Maps: Total cpu/real time 
for sm: 106.71/11.8953
PID6803 2017-02-27 14:03:47,417 Info:Linear Algebra: Starting
PID6803 2017-02-27 14:03:47,418 Debug:Linear Algebra: linalgdlp.run(): Task 
state: {}
PID6803 2017-02-27 14:03:47,424 Info:Linear Algebra: Using 2 as default value 
for n to account for Schirokauer maps
PID6803 2017-02-27 14:03:47,425 Info:Linear Algebra: Using 2*n=4 as default 
value for m
PID6803 2017-02-27 14:03:47,429 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl :complete 'thr=10' 'm=4' 'n=2' 
'nullspace=right' 'matrix=/home/user/dlp/descent/p60.sparse.bin' 
'rhs=/home/user/dlp/descent/p60.sm' 
'prime=157079632679489661923132169163975144209858469968755291072921' 
'wdir=/home/user/dlp/descent/p60.bwc' 
'cpubinding=/home/user/cado/cado_build/share/cado-nfs-2.2.1/misc/cpubinding.conf'
 'cantor_threshold=2048' > /home/user/dlp/descent/p60.bwc.stdout.1 2> 
/home/user/dlp/descent/p60.bwc.stderr.1
PID6803 2017-02-27 14:06:30,266 Debug:Command: Process with PID 15690 
finished successfully
PID6803 2017-02-27 14:06:30,271 Debug:Linear Algebra: Exit 
LinAlgTask.run(linalgdlp)
PID6803 2017-02-27 14:06:30,272 Info:Linear Algebra: Total cpu/real time for 
bwc: 480.23/162.837
PID6803 2017-02-27 14:06:30,272 Info:Logarithms Reconstruction: Starting
PID6803 2017-02-27 14:06:30,272 Debug:Logarithms Reconstruction: 
reconstructlog.run(): Task state: {}
PID6803 2017-02-27 14:06:30,278 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/reconstructlog-dl -ell 
157079632679489661923132169163975144209858469968755291072921 -mt 10 -log 
/home/user/dlp/descent/p60.bwc/K.sols0-1.0.txt -out 
/home/user/dlp/descent/p60.dlog -renumber 
/home/user/dlp/descent/p60.renumber.gz -poly /home/user/dlp/descent/p60.poly 
-purged /home/user/dlp/descent/p60.purged.gz -ideals 
/home/user/dlp/descent/p60.ideal -relsdel 
/home/user/dlp/descent/p60.relsdel.gz -nrels 778755 -nsm '0,2' > 
/home/user/dlp/descent/p60.reconstructlog-dl.stdout.1 2> 
/home/user/dlp/descent/p60.reconstructlog-dl.stderr.1
PID6803 2017-02-27 14:32:35,692 Debug:Command: Process with PID 15823 
finished successfully
PID6803 2017-02-27 14:32:35,702 Debug:Logarithms Reconstruction: Exit 
ReconstructLogTask.run(reconstructlog)
PID6803 2017-02-27 14:32:35,702 Info:Logarithms Reconstruction: Total 
cpu/real time for reconstructlog-dl: 15011.1/1565.41
PID6803 2017-02-27 14:32:35,702 Info:Individual logarithm: Starting
PID6803 2017-02-27 14:32:35,702 Debug:Individual logarithm: descent.run(): 
Task state: {}
PID6803 2017-02-27 14:32:35,707 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/scripts/descent.py --target 4 --prefix p60 
--datadir /home/user/dlp/descent --cadobindir $CADO_DIR/lib/cado-nfs-2.2.1 
--descent-hint $CADO_DIR/share/cado-nfs-2.2.1/dlp/p60.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 
500000 --lim1 600000 --ell 
157079632679489661923132169163975144209858469968755291072921 > 
/home/user/dlp/descent/p60.descent.stdout.1 2> 
/home/user/dlp/descent/p60.descent.stderr.1
PID6803 2017-02-27 14:32:39,249 Debug:Command: Process with PID 21504 
finished successfully
PID6803 2017-02-27 14:32:39,327 Info:Individual logarithm: Total cpu/real 
time for descent: 3.62/3.54159
PID6803 2017-02-27 14:32:39,328 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID6803 2017-02-27 14:32:39,328 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID6803 2017-02-27 14:32:39,328 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 181/19.150/22.041/27.570/1.304
PID6803 2017-02-27 14:32:39,328 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 181/18.970/21.047/23.660/0.855
PID6803 2017-02-27 14:32:39,329 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID6803 2017-02-27 14:32:39,329 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID6803 2017-02-27 14:32:39,329 Info:Polynomial Selection (size optimized): 
Total time: 3.04
PID6803 2017-02-27 14:32:39,329 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID6803 2017-02-27 14:32:39,330 Info:Polynomial Selection (root optimized): 
Total time: 70.14
PID6803 2017-02-27 14:32:39,330 Info:Polynomial Selection (root optimized): 
Rootsieve time: 66.07
PID6803 2017-02-27 14:32:39,330 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.11/0.113048
PID6803 2017-02-27 14:32:39,331 Info:Generate Factor Base: Total cpu/real 
time for makefb: 1.89/0.379226
PID6803 2017-02-27 14:32:39,331 Info:Generate Free Relations: Total cpu/real 
time for freerel: 16.22/2.16185
PID6803 2017-02-27 14:32:39,331 Info:Lattice Sieving: Aggregate statistics:
PID6803 2017-02-27 14:32:39,332 Info:Lattice Sieving: Total number of 
relations: 1036192
PID6803 2017-02-27 14:32:39,332 Info:Lattice Sieving: Average J: 935.058 for 
534 special-q, max bucket fill: 0.640261
PID6803 2017-02-27 14:32:39,332 Info:Lattice Sieving: Total CPU time: 316.63s
PID6803 2017-02-27 14:32:39,332 Info:Filtering - Duplicate Removal, splitting 
pass: Total cpu/real time for dup1: 4.56/5.26723
PID6803 2017-02-27 14:32:39,333 Info:Filtering - Duplicate Removal, splitting 
pass: Aggregate statistics:
PID6803 2017-02-27 14:32:39,333 Info:Filtering - Duplicate Removal, splitting 
pass: CPU time for dup1: 5.0s
PID6803 2017-02-27 14:32:39,333 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 17.99/9.42945
PID6803 2017-02-27 14:32:39,334 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 32.91/17.243
PID6803 2017-02-27 14:32:39,334 Info:Filtering - Merging: Total cpu/real time 
for merge-dl: 3.19/1.63952
PID6803 2017-02-27 14:32:39,334 Info:Filtering - Merging: Total cpu/real time 
for replay-dl: 0.21/0.246538
PID6803 2017-02-27 14:32:39,334 Info:Schirokauer Maps: Total cpu/real time 
for sm: 106.71/11.8953
PID6803 2017-02-27 14:32:39,335 Info:Linear Algebra: Total cpu/real time for 
bwc: 480.23/162.837
PID6803 2017-02-27 14:32:39,335 Info:Logarithms Reconstruction: Total 
cpu/real time for reconstructlog-dl: 15011.1/1565.41
PID6803 2017-02-27 14:32:39,335 Info:Individual logarithm: Total cpu/real 
time for descent: 3.62/3.54159
PID6803 2017-02-27 14:32:39,335 Info:HTTP server: Shutting down HTTP server
PID6803 2017-02-27 14:32:39,481 Info:Complete Factorization: Total 
cpu/elapsed time for entire discrete log: 16068.6/1919.22
PID6803 2017-02-27 14:32:39,591 Info:root: If you want to compute a new 
target, run ./cado-nfs.py /home/user/dlp/descent/p60.parameters_snapshot.0 
target=<target>

Attachment: p60_1.descent.stdout.1
Description: Binary data

PID7272 2017-02-27 15:36:49,274 Info:root: Command line parameters: 
./cado-nfs.py -dlp -ell 
157079632679489661923132169163975144209858469968755291072921 target=4 
314159265358979323846264338327950288419716939937510582145843 server.ssl=no -w 
/home/user/dlp/60_ok
PID7272 2017-02-27 15:36:49,274 Debug:root: Root parameter dictionary:
N = 314159265358979323846264338327950288419716939937510582145843
descent_hint = $CADO_DIR/share/cado-nfs-2.2.1/dlp/p60.hint
dlp = True
ell = 157079632679489661923132169163975144209858469968755291072921
gfpext = 1
lim0 = 500000
lim1 = 600000
lpb0 = 22
lpb1 = 22
name = p60
target = 4
server.ssl = no
slaves.basepath = /home/user/dlp/60_ok/client
slaves.hostnames = localhost
slaves.nrclients = 5
slaves.scriptpath = $CADO_DIR/bin
tasks.I = 11
tasks.execpath = $CADO_DIR/lib/cado-nfs-2.2.1
tasks.threads = 10
tasks.workdir = /home/user/dlp/60_ok
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 = 500000
tasks.descent.lim1 = 600000
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 = 
$CADO_DIR/share/cado-nfs-2.2.1/misc/cpubinding.conf
tasks.linalg.bwc.thr = 4x2
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 = 200000
tasks.sieve.qrange = 1000
tasks.sieve.rels_wanted = 1000000
tasks.sieve.las.threads = 2
tasks.sqrt.sqrt.threads = 8
PID7272 2017-02-27 15:36:49,274 Debug:root: Writing parameter snapshot to 
/home/user/dlp/60_ok/p60.parameters_snapshot.0
PID7272 2017-02-27 15:36:49,275 Info:root: If this computation gets 
interrupted, it can be resumed with ./cado-nfs.py 
/home/user/dlp/60_ok/p60.parameters_snapshot.0
PID7272 2017-02-27 15:36:50,044 Debug:Client Launcher: Enter 
Task.__init__(slaves)
PID7272 2017-02-27 15:36:50,044 Debug:Client Launcher: state = {}
PID7272 2017-02-27 15:36:50,045 Debug:Client Launcher: self.parameters = 
MyParameters: name = None, prefix = ['slaves']
PID7272 2017-02-27 15:36:50,045 Debug:Client Launcher: params = 
{'scriptpath': '/home/user/cado/cado_build/bin', 'run': True, 'hostnames': 
'localhost', 'nrclients': 5}
PID7272 2017-02-27 15:36:50,046 Debug:Client Launcher: Exit 
Task.__init__(slaves)
PID7272 2017-02-27 15:36:50,205 Info:Server Launcher: Adding 192.168.1.97 to 
whitelist to allow clients on localhost to connect
PID7272 2017-02-27 15:36:50,332 Debug:HTTP server: Found upload.py at 
$CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:36:50,341 Info:HTTP server: Using non-threaded HTTP 
server
PID7272 2017-02-27 15:36:50,342 Info:HTTP server: Using whitelist: 
localhost,192.168.1.97
PID7272 2017-02-27 15:36:50,407 Debug:Generate Factor Base: Enter 
Task.__init__(factorbase)
PID7272 2017-02-27 15:36:50,407 Debug:Generate Factor Base: state = {}
PID7272 2017-02-27 15:36:50,407 Debug:Generate Factor Base: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID7272 2017-02-27 15:36:50,407 Debug:Generate Factor Base: params = {'run': 
True, 'lim0': 500000, 'I': 11, 'gzip': True, 'lim1': 600000, 'name': 'p60', 
'workdir': '/home/user/dlp/60_ok'}
PID7272 2017-02-27 15:36:50,408 Debug:Generate Factor Base: Exit 
Task.__init__(factorbase)
PID7272 2017-02-27 15:36:50,419 Debug:Generate Free Relations: Enter 
Task.__init__(freerel)
PID7272 2017-02-27 15:36:50,419 Debug:Generate Free Relations: state = {}
PID7272 2017-02-27 15:36:50,419 Debug:Generate Free Relations: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'sieve']
PID7272 2017-02-27 15:36:50,419 Debug:Generate Free Relations: params = 
{'dlp': True, 'name': 'p60', 'workdir': '/home/user/dlp/60_ok', 'run': True, 
'gzip': True}
PID7272 2017-02-27 15:36:50,420 Debug:Generate Free Relations: Exit 
Task.__init__(freerel)
PID7272 2017-02-27 15:36:50,505 Debug:Lattice Sieving: Enter 
Task.__init__(sieving)
PID7272 2017-02-27 15:36:50,506 Debug:Lattice Sieving: state = {}
PID7272 2017-02-27 15:36:50,506 Debug:Lattice Sieving: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'sieve']
PID7272 2017-02-27 15:36:50,506 Debug:Lattice Sieving: params = {'qmin': 
200000, 'qrange': 1000, 'lim1': 600000, 'name': 'p60', 'wutimeout': 10800, 
'maxtimedout': 100, 'maxfailed': 100, 'rels_wanted': 1000000, 'run': True, 
'maxwu': 10, 'gzip': True, 'workdir': '/home/user/dlp/60_ok', 'maxresubmit': 
5}
PID7272 2017-02-27 15:36:50,507 Debug:Lattice Sieving: Exit 
Task.__init__(sieving)
PID7272 2017-02-27 15:36:50,768 Debug:Filtering - Duplicate Removal, 
splitting pass: Enter Task.__init__(duplicates1)
PID7272 2017-02-27 15:36:50,769 Debug:Filtering - Duplicate Removal, 
splitting pass: state = {}
PID7272 2017-02-27 15:36:50,769 Debug:Filtering - Duplicate Removal, 
splitting pass: self.parameters = MyParameters: name = None, prefix = 
['tasks', 'filter']
PID7272 2017-02-27 15:36:50,769 Debug:Filtering - Duplicate Removal, 
splitting pass: params = {'name': 'p60', 'run': True, 'workdir': 
'/home/user/dlp/60_ok', 'nslices_log': 1}
PID7272 2017-02-27 15:36:50,770 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Task.__init__(duplicates1)
PID7272 2017-02-27 15:36:51,196 Debug:Filtering - Duplicate Removal, removal 
pass: Enter Task.__init__(duplicates2)
PID7272 2017-02-27 15:36:51,196 Debug:Filtering - Duplicate Removal, removal 
pass: state = {}
PID7272 2017-02-27 15:36:51,197 Debug:Filtering - Duplicate Removal, removal 
pass: self.parameters = MyParameters: name = None, prefix = ['tasks', 
'filter']
PID7272 2017-02-27 15:36:51,197 Debug:Filtering - Duplicate Removal, removal 
pass: params = {'name': 'p60', 'dlp': True, 'workdir': 
'/home/user/dlp/60_ok', 'run': True, 'nslices_log': 1}
PID7272 2017-02-27 15:36:51,198 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Task.__init__(duplicates2)
PID7272 2017-02-27 15:36:51,279 Debug:Filtering - Singleton removal: Enter 
Task.__init__(purge)
PID7272 2017-02-27 15:36:51,280 Debug:Filtering - Singleton removal: state = 
{}
PID7272 2017-02-27 15:36:51,280 Debug:Filtering - Singleton removal: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'filter']
PID7272 2017-02-27 15:36:51,280 Debug:Filtering - Singleton removal: params = 
{'dlp': True, 'required_excess': 0.0, 'galois': 'none', 'gzip': True, 'name': 
'p60', 'add_ratio': 0.01, 'workdir': '/home/user/dlp/60_ok', 'run': True}
PID7272 2017-02-27 15:36:51,281 Debug:Filtering - Singleton removal: Exit 
Task.__init__(purge)
PID7272 2017-02-27 15:36:51,396 Debug:Polynomial Selection (size optimized): 
Enter Task.__init__(polyselect1)
PID7272 2017-02-27 15:36:51,397 Debug:Polynomial Selection (size optimized): 
state = {}
PID7272 2017-02-27 15:36:51,397 Debug:Polynomial Selection (size optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID7272 2017-02-27 15:36:51,397 Debug:Polynomial Selection (size optimized): 
params = {'lim0': 500000, 'admax': 1000, 'N': 
314159265358979323846264338327950288419716939937510582145843, 'adrange': 500, 
'name': 'p60', 'wutimeout': 10800, 'lim1': 600000, 'maxtimedout': 100, 
'admin': 0, 'nrkeep': 20, 'maxwu': 10, 'I': 11, 'workdir': 
'/home/user/dlp/60_ok', 'run': True, 'maxresubmit': 5, 'maxfailed': 100}
PID7272 2017-02-27 15:36:51,397 Debug:Polynomial Selection (size optimized): 
Exit Task.__init__(polyselect1)
PID7272 2017-02-27 15:36:51,475 Debug:Polynomial Selection (root optimized): 
Enter Task.__init__(polyselect2)
PID7272 2017-02-27 15:36:51,475 Debug:Polynomial Selection (root optimized): 
state = {}
PID7272 2017-02-27 15:36:51,476 Debug:Polynomial Selection (root optimized): 
self.parameters = MyParameters: name = None, prefix = ['tasks', 'polyselect']
PID7272 2017-02-27 15:36:51,476 Debug:Polynomial Selection (root optimized): 
params = {'lim0': 500000, 'N': 
314159265358979323846264338327950288419716939937510582145843, 'lim1': 600000, 
'run': True, 'wutimeout': 10800, 'maxtimedout': 100, 'maxfailed': 100, 
'name': 'p60', 'maxwu': 10, 'I': 11, 'workdir': '/home/user/dlp/60_ok', 
'maxresubmit': 5}
PID7272 2017-02-27 15:36:51,476 Debug:Polynomial Selection (root optimized): 
Exit Task.__init__(polyselect2)
PID7272 2017-02-27 15:36:51,510 Debug:Number Theory for DLP: Enter 
Task.__init__(numbertheory)
PID7272 2017-02-27 15:36:51,511 Debug:Number Theory for DLP: state = {}
PID7272 2017-02-27 15:36:51,511 Debug:Number Theory for DLP: self.parameters 
= MyParameters: name = None, prefix = ['tasks']
PID7272 2017-02-27 15:36:51,511 Debug:Number Theory for DLP: params = 
{'name': 'p60', 'run': True, 'workdir': '/home/user/dlp/60_ok', 'nsm0': -1, 
'nsm1': -1}
PID7272 2017-02-27 15:36:51,511 Debug:Number Theory for DLP: Exit 
Task.__init__(numbertheory)
PID7272 2017-02-27 15:36:51,520 Debug:Filtering - Galois: Enter 
Task.__init__(filtergalois)
PID7272 2017-02-27 15:36:51,521 Debug:Filtering - Galois: state = {}
PID7272 2017-02-27 15:36:51,521 Debug:Filtering - Galois: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID7272 2017-02-27 15:36:51,521 Debug:Filtering - Galois: params = {'name': 
'p60', 'run': True, 'workdir': '/home/user/dlp/60_ok', 'galois': 'none'}
PID7272 2017-02-27 15:36:51,521 Debug:Filtering - Galois: Exit 
Task.__init__(filtergalois)
PID7272 2017-02-27 15:36:51,897 Debug:Schirokauer Maps: Enter 
Task.__init__(sm)
PID7272 2017-02-27 15:36:51,897 Debug:Schirokauer Maps: state = {}
PID7272 2017-02-27 15:36:51,897 Debug:Schirokauer Maps: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID7272 2017-02-27 15:36:51,898 Debug:Schirokauer Maps: params = {'name': 
'p60', 'run': True, 'workdir': '/home/user/dlp/60_ok'}
PID7272 2017-02-27 15:36:51,898 Debug:Schirokauer Maps: Exit Task.__init__(sm)
PID7272 2017-02-27 15:36:52,010 Debug:Filtering - Merging: Enter 
Task.__init__(mergedlp)
PID7272 2017-02-27 15:36:52,010 Debug:Filtering - Merging: state = {}
PID7272 2017-02-27 15:36:52,010 Debug:Filtering - Merging: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'filter']
PID7272 2017-02-27 15:36:52,010 Debug:Filtering - Merging: params = {'name': 
'p60', 'run': True, 'workdir': '/home/user/dlp/60_ok', 'gzip': True}
PID7272 2017-02-27 15:36:52,011 Debug:Filtering - Merging: Exit 
Task.__init__(mergedlp)
PID7272 2017-02-27 15:36:52,292 Debug:Linear Algebra: Enter 
Task.__init__(linalgdlp)
PID7272 2017-02-27 15:36:52,293 Debug:Linear Algebra: state = {}
PID7272 2017-02-27 15:36:52,293 Debug:Linear Algebra: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'linalg']
PID7272 2017-02-27 15:36:52,293 Debug:Linear Algebra: params = {'run': True, 
'name': 'p60', 'm': 0, 'workdir': '/home/user/dlp/60_ok', 'ell': 
157079632679489661923132169163975144209858469968755291072921, 'n': 0}
PID7272 2017-02-27 15:36:52,294 Debug:Linear Algebra: Exit 
Task.__init__(linalgdlp)
PID7272 2017-02-27 15:36:52,614 Debug:Logarithms Reconstruction: Enter 
Task.__init__(reconstructlog)
PID7272 2017-02-27 15:36:52,614 Debug:Logarithms Reconstruction: state = {}
PID7272 2017-02-27 15:36:52,615 Debug:Logarithms Reconstruction: 
self.parameters = MyParameters: name = None, prefix = ['tasks', 
'reconstructlog']
PID7272 2017-02-27 15:36:52,615 Debug:Logarithms Reconstruction: params = 
{'name': 'p60', 'run': True, 'workdir': '/home/user/dlp/60_ok', 'partial': 
False, 'checkdlp': True}
PID7272 2017-02-27 15:36:52,615 Debug:Logarithms Reconstruction: Exit 
Task.__init__(reconstructlog)
PID7272 2017-02-27 15:36:52,764 Debug:Individual logarithm: Enter 
Task.__init__(descent)
PID7272 2017-02-27 15:36:52,765 Debug:Individual logarithm: state = {}
PID7272 2017-02-27 15:36:52,765 Debug:Individual logarithm: self.parameters = 
MyParameters: name = None, prefix = ['tasks', 'descent']
PID7272 2017-02-27 15:36:52,766 Debug:Individual logarithm: params = 
{'descent_hint': 
'/home/user/cado/cado_build/share/cado-nfs-2.2.1/dlp/p60.hint', 'target': 4, 
'lim0': 500000, 'mfb1': 44, 'lim1': 600000, 'init_lpb': 24, 'init_ncurves': 
10, 'init_mfb': 48, 'lpb1': 22, 'mfb0': 44, 'name': 'p60', 'init_lim': 
100000, 'execpath': '/home/user/cado/cado_build/lib/cado-nfs-2.2.1', 
'init_I': 11, 'init_tkewness': 200000, 'I': 11, 'lpb0': 22, 'workdir': 
'/home/user/dlp/60_ok', 'run': True}
PID7272 2017-02-27 15:36:52,766 Debug:Individual logarithm: Exit 
Task.__init__(descent)
PID7272 2017-02-27 15:36:52,767 Warning:Complete Factorization: Parameter 
tasks.linalg.bwc.thr = 4x2 was not used anywhere
PID7272 2017-02-27 15:36:52,767 Warning:Complete Factorization: Parameter 
tasks.sqrt.sqrt.threads = 8 was not used anywhere
PID7272 2017-02-27 15:36:52,767 Info:Complete Factorization: Computing 
Discrete Logs in 
GF(314159265358979323846264338327950288419716939937510582145843)
PID7272 2017-02-27 15:36:52,814 Info:HTTP server: serving at 
http://192.168.1.97:43582 (0.0.0.0)
PID7272 2017-02-27 15:36:52,814 Info:HTTP server: For debugging purposes, the 
URL above can be accessed if the server.only_registered=False parameter is 
added
PID7272 2017-02-27 15:36:52,814 Info:HTTP server: You can start additional 
cado-nfs-client.py scripts with parameters: --server=http://192.168.1.97:43582
PID7272 2017-02-27 15:36:52,814 Info:HTTP server: If you want to start 
additional clients, remember to add their hosts to server.whitelist
PID7272 2017-02-27 15:36:52,823 Info:Client Launcher: Starting client id 
localhost on host localhost
PID7272 2017-02-27 15:36:52,826 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:43582 --daemon 
--clientid localhost --basepath /home/user/dlp/60_ok/client
PID7272 2017-02-27 15:36:53,808 Debug:Command: Process with PID 7281 finished 
successfully
PID7272 2017-02-27 15:36:53,808 Debug:Command: Process with PID 7281 stdout: 
b'PID: 7282\n'
PID7272 2017-02-27 15:36:53,808 Debug:Command: Process with PID 7281 stderr: 
b'INFO:root:Starting client localhost\nINFO:root:Python version is 2.7.5\n'
PID7272 2017-02-27 15:36:54,530 Info:Client Launcher: Starting client id 
localhost+2 on host localhost
PID7272 2017-02-27 15:36:54,536 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:43582 --daemon 
--clientid localhost+2 --basepath /home/user/dlp/60_ok/client
PID7272 2017-02-27 15:36:54,611 Debug:Command: Process with PID 7283 finished 
successfully
PID7272 2017-02-27 15:36:54,611 Debug:Command: Process with PID 7283 stdout: 
b'PID: 7284\n'
PID7272 2017-02-27 15:36:54,611 Debug:Command: Process with PID 7283 stderr: 
b'INFO:root:Starting client localhost+2\nINFO:root:Python version is 2.7.5\n'
PID7272 2017-02-27 15:36:54,750 Info:Client Launcher: Starting client id 
localhost+3 on host localhost
PID7272 2017-02-27 15:36:54,753 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:43582 --daemon 
--clientid localhost+3 --basepath /home/user/dlp/60_ok/client
PID7272 2017-02-27 15:36:55,597 Debug:Command: Process with PID 7285 finished 
successfully
PID7272 2017-02-27 15:36:55,598 Debug:Command: Process with PID 7285 stdout: 
b'PID: 7287\n'
PID7272 2017-02-27 15:36:55,598 Debug:Command: Process with PID 7285 stderr: 
b'INFO:root:Starting client localhost+3\nINFO:root:Python version is 2.7.5\n'
PID7272 2017-02-27 15:36:55,748 Info:Client Launcher: Starting client id 
localhost+4 on host localhost
PID7272 2017-02-27 15:36:55,752 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:43582 --daemon 
--clientid localhost+4 --basepath /home/user/dlp/60_ok/client
PID7272 2017-02-27 15:36:55,831 Debug:Command: Process with PID 7288 finished 
successfully
PID7272 2017-02-27 15:36:55,831 Debug:Command: Process with PID 7288 stdout: 
b'PID: 7289\n'
PID7272 2017-02-27 15:36:55,831 Debug:Command: Process with PID 7288 stderr: 
b'INFO:root:Starting client localhost+4\nINFO:root:Python version is 2.7.5\n'
PID7272 2017-02-27 15:36:55,871 Info:Client Launcher: Starting client id 
localhost+5 on host localhost
PID7272 2017-02-27 15:36:55,873 Command:Command: 
$CADO_DIR/bin/cado-nfs-client.py --server http://localhost:43582 --daemon 
--clientid localhost+5 --basepath /home/user/dlp/60_ok/client
PID7272 2017-02-27 15:36:55,941 Debug:Command: Process with PID 7290 finished 
successfully
PID7272 2017-02-27 15:36:55,941 Debug:Command: Process with PID 7290 stdout: 
b'PID: 7299\n'
PID7272 2017-02-27 15:36:55,941 Debug:Command: Process with PID 7290 stderr: 
b'INFO:root:Starting client localhost+5\nINFO:root:Python version is 2.7.5\n'
PID7272 2017-02-27 15:36:55,963 Info:Client Launcher: Running clients: 
localhost (Host localhost, PID 7282), localhost+4 (Host localhost, PID 7289), 
localhost+2 (Host localhost, PID 7284), localhost+5 (Host localhost, PID 
7299), localhost+3 (Host localhost, PID 7287)
PID7272 2017-02-27 15:36:55,963 Info:Polynomial Selection (size optimized): 
Starting
PID7272 2017-02-27 15:36:55,964 Debug:Polynomial Selection (size optimized): 
polyselect1.run(): Task state: {'start_achievement': -1, 'wu_failed': 0, 
'wu_received': 0, 'adnext': 0, 'start_real_time': 0, 'wu_timedout': 0, 
'wu_submitted': 0}
PID7272 2017-02-27 15:36:55,964 Info:Polynomial Selection (size optimized): 0 
polynomials in queue from previous run
PID7272 2017-02-27 15:36:55,966 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID7272 2017-02-27 15:36:55,968 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect is 
('523ee132ce36187ad068440240d54a270c8e65f0', (155049494, 919857, 
1488182382.0))
PID7272 2017-02-27 15:36:55,968 Debug:Server Launcher: Registering file name 
polyselect with target $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID7272 2017-02-27 15:36:55,974 Info:Polynomial Selection (size optimized): 
Adding workunit p60_polyselect1_0-500 to database
PID7272 2017-02-27 15:36:56,006 Info:Polynomial Selection (size optimized): 
Adding workunit p60_polyselect1_500-1000 to database
PID7272 2017-02-27 15:36:56,066 Debug:Polynomial Selection (size optimized): 
Setting last timeout check to 1488199016.066536
PID7272 2017-02-27 15:37:04,600 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect1_0-500 to client localhost
PID7272 2017-02-27 15:37:04,600 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:04,894 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect1_500-1000 to client localhost+2
PID7272 2017-02-27 15:37:04,894 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:06,509 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect to $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID7272 2017-02-27 15:37:06,510 Debug:HTTP server: 127.0.0.1 "GET /polyselect 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:06,514 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect to $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect
PID7272 2017-02-27 15:37:06,514 Debug:HTTP server: 127.0.0.1 "GET /polyselect 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:06,515 Warning:HTTP server: 127.0.0.1 Connection 
error: [Errno 104] Connection reset by peer
PID7272 2017-02-27 15:37:07,278 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:07,279 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:07,558 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:07,558 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:13,189 Info:Polynomial Selection (size optimized): 
Parsed 106 polynomials, added 42 to priority queue (has 20)
PID7272 2017-02-27 15:37:13,189 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 17.320000
PID7272 2017-02-27 15:37:13,199 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '1.83', 'stats_rawlognorm': '106 
19.15 22.0 27.57 1.49', 'stats_collisions': '96.13', 'stats_optlognorm': '106 
18.97 21.01 22.61 0.89'}
PID7272 2017-02-27 15:37:13,199 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '1.83', 'stats_rawlognorm': '106 19.15 
22.0 27.57 1.49', 'stats_collisions': '96.13', 'stats_optlognorm': '106 18.97 
21.01 22.61 0.89'}
PID7272 2017-02-27 15:37:13,330 Info:Polynomial Selection (size optimized): 
Marking workunit p60_polyselect1_0-500 as ok (50.0% => ETA Unknown)
PID7272 2017-02-27 15:37:13,961 Info:Polynomial Selection (size optimized): 
Parsed 75 polynomials, added 5 to priority queue (has 20)
PID7272 2017-02-27 15:37:13,961 Info:Polynomial Selection (size optimized): 
Worst polynomial in queue now has lognorm 17.190000
PID7272 2017-02-27 15:37:13,979 Debug:Polynomial Selection (size optimized): 
Newly arrived stats: {'stats_total_time': '1.4', 'stats_rawlognorm': '75 
20.29 22.1 25.69 0.98', 'stats_collisions': '96.13', 'stats_optlognorm': '75 
19.18 21.1 23.66 0.8'}
PID7272 2017-02-27 15:37:13,979 Debug:Polynomial Selection (size optimized): 
Combined stats: {'stats_total_time': '3.23', 'stats_rawlognorm': '181 19.15 
22.0414364640884 27.57 1.304051965119179', 'stats_collisions': '192.26', 
'stats_optlognorm': '181 18.97 21.04729281767956 23.66 0.8550092031447349'}
PID7272 2017-02-27 15:37:13,982 Info:Polynomial Selection (size optimized): 
Marking workunit p60_polyselect1_500-1000 as ok (100.0% => ETA Mon Feb 27 
15:37:13 2017)
PID7272 2017-02-27 15:37:14,110 Info:Polynomial Selection (size optimized): 
Finished
PID7272 2017-02-27 15:37:14,110 Info:Polynomial Selection (size optimized): 
Aggregate statistics:
PID7272 2017-02-27 15:37:14,111 Info:Polynomial Selection (size optimized): 
potential collisions: 192.26
PID7272 2017-02-27 15:37:14,111 Info:Polynomial Selection (size optimized): 
raw lognorm (nr/min/av/max/std): 181/19.150/22.041/27.570/1.304
PID7272 2017-02-27 15:37:14,111 Info:Polynomial Selection (size optimized): 
optimized lognorm (nr/min/av/max/std): 181/18.970/21.047/23.660/0.855
PID7272 2017-02-27 15:37:14,111 Info:Polynomial Selection (size optimized): 
10 best raw logmu:
PID7272 2017-02-27 15:37:14,112 Info:Polynomial Selection (size optimized): 
10 best opt logmu:
PID7272 2017-02-27 15:37:14,112 Info:Polynomial Selection (size optimized): 
Total time: 3.23
PID7272 2017-02-27 15:37:14,112 Info:Polynomial Selection (root optimized): 
Starting
PID7272 2017-02-27 15:37:14,112 Debug:Polynomial Selection (root optimized): 
polyselect2.run(): Task state: {'start_achievement': -1, 'wu_failed': 0, 
'wu_received': 0, 'nr_poly_submitted': 0, 'start_real_time': 0, 
'wu_timedout': 0, 'wu_submitted': 0}
PID7272 2017-02-27 15:37:14,113 Info:Polynomial Selection (root optimized): 
No polynomial was previously found
PID7272 2017-02-27 15:37:14,117 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_0
PID7272 2017-02-27 15:37:14,117 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_0 is 
('e8a56c6af19103d7e560bc91dc5c9f27e1695adc', (12273517, 1798, 
1488199034.1135283))
PID7272 2017-02-27 15:37:14,117 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID7272 2017-02-27 15:37:14,121 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt is 
('1b358450bdfb6ff3f560a5a4c4ec1d6fa276c243', (155049495, 823886, 
1488182381.0))
PID7272 2017-02-27 15:37:14,122 Debug:Server Launcher: Registering file name 
polyselect_ropt with target 
$CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID7272 2017-02-27 15:37:14,138 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_0 with target /home/user/dlp/60_ok/p60.polyselect2.raw_0
PID7272 2017-02-27 15:37:14,161 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_0 to database
PID7272 2017-02-27 15:37:15,292 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_6
PID7272 2017-02-27 15:37:15,293 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_6 is 
('556472af3ffbb8e3220ca06f91072940d754fc54', (12273518, 1778, 
1488199035.2895281))
PID7272 2017-02-27 15:37:15,294 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_6 with target /home/user/dlp/60_ok/p60.polyselect2.raw_6
PID7272 2017-02-27 15:37:15,484 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_6 to database
PID7272 2017-02-27 15:37:15,601 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_12
PID7272 2017-02-27 15:37:15,601 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_12 is 
('22c74e9b88e2adfc6d5f3ea30ca2bbef0e2cceeb', (12273519, 1794, 
1488199035.5995283))
PID7272 2017-02-27 15:37:15,603 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_12 with target /home/user/dlp/60_ok/p60.polyselect2.raw_12
PID7272 2017-02-27 15:37:15,610 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_12 to database
PID7272 2017-02-27 15:37:15,620 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_18
PID7272 2017-02-27 15:37:15,620 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.polyselect2.raw_18 is 
('f15c344913a60c1cf834d1aa3f9909f178a04b94', (12273520, 589, 
1488199035.6185281))
PID7272 2017-02-27 15:37:15,621 Debug:Server Launcher: Registering file name 
p60.polyselect2.raw_18 with target /home/user/dlp/60_ok/p60.polyselect2.raw_18
PID7272 2017-02-27 15:37:15,652 Info:Polynomial Selection (root optimized): 
Adding workunit p60_polyselect2_18 to database
PID7272 2017-02-27 15:37:15,708 Debug:Polynomial Selection (root optimized): 
Setting last timeout check to 1488199035.708204
PID7272 2017-02-27 15:37:15,828 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_0 to client localhost+3
PID7272 2017-02-27 15:37:15,828 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:15,830 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_0 to /home/user/dlp/60_ok/p60.polyselect2.raw_0
PID7272 2017-02-27 15:37:15,830 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_0 HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:15,832 Debug:HTTP server: 127.0.0.1 Translated path 
/polyselect_ropt to $CADO_DIR/lib/cado-nfs-2.2.1/polyselect/polyselect_ropt
PID7272 2017-02-27 15:37:15,832 Debug:HTTP server: 127.0.0.1 "GET 
/polyselect_ropt HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:15,929 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_6 to client localhost+4
PID7272 2017-02-27 15:37:15,929 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:15,931 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_6 to /home/user/dlp/60_ok/p60.polyselect2.raw_6
PID7272 2017-02-27 15:37:15,931 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_6 HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:16,020 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_12 to client localhost+5
PID7272 2017-02-27 15:37:16,020 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:16,026 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_12 to /home/user/dlp/60_ok/p60.polyselect2.raw_12
PID7272 2017-02-27 15:37:16,026 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_12 HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:17,676 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_polyselect2_18 to client localhost+2
PID7272 2017-02-27 15:37:17,677 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:17,684 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.polyselect2.raw_18 to /home/user/dlp/60_ok/p60.polyselect2.raw_18
PID7272 2017-02-27 15:37:17,684 Debug:HTTP server: 127.0.0.1 "GET 
/p60.polyselect2.raw_18 HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:23,041 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:23,041 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:27,750 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/60_ok/p60.upload/p60.polyselect2.zxtjpzoo.opt_18: Murphy E = 
2.35e-06
PID7272 2017-02-27 15:37:27,751 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 314159265358979323846264338327950288419716939937510582145843
skew: 2609.102
c0: 28714187096082117
c1: -81173262014511
c2: -18556976378
c3: 11732750
c4: 600
Y0: 96073988789362
Y1: 51214779733
# MurphyE (Bf=6.00e+05,Bg=5.00e+05,area=1.26e+12) = 2.35e-06
# f(x) = 
600*x^4+11732750*x^3-18556976378*x^2-81173262014511*x+28714187096082117
# g(x) = 51214779733*x+96073988789362

PID7272 2017-02-27 15:37:27,753 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '9.48', 'stats_rootsieve_time': 
'9.0'}
PID7272 2017-02-27 15:37:27,753 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '9.48', 'stats_rootsieve_time': '9.0'}
PID7272 2017-02-27 15:37:27,841 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:27,841 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:27,906 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_18 as ok (10.0% => ETA Unknown)
PID7272 2017-02-27 15:37:27,950 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:27,950 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:28,508 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:28,509 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:37:28,919 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/60_ok/p60.upload/p60.polyselect2.gg41sy84.opt_0 with 
E=2.26e-06 is no better than current best with E=2.35e-06
PID7272 2017-02-27 15:37:28,920 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '22.79', 'stats_rootsieve_time': 
'21.36'}
PID7272 2017-02-27 15:37:28,920 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '32.269999999999996', 
'stats_rootsieve_time': '30.36'}
PID7272 2017-02-27 15:37:28,921 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_0 as ok (40.0% => ETA Mon Feb 27 15:37:58 
2017)
PID7272 2017-02-27 15:37:29,049 Info:Polynomial Selection (root optimized): 
New best polynomial from file 
/home/user/dlp/60_ok/p60.upload/p60.polyselect2.4omecehb.opt_6: Murphy E = 
2.67e-06
PID7272 2017-02-27 15:37:29,049 Debug:Polynomial Selection (root optimized): 
New best polynomial is:
n: 314159265358979323846264338327950288419716939937510582145843
skew: 4826.582
c0: 1874158438742675
c1: 20428056679672
c2: -1499008127
c3: -276532
c4: 120
Y0: -237731156825892
Y1: 20070872717
# MurphyE (Bf=6.00e+05,Bg=5.00e+05,area=1.26e+12) = 2.67e-06
# f(x) = 120*x^4-276532*x^3-1499008127*x^2+20428056679672*x+1874158438742675
# g(x) = 20070872717*x-237731156825892

PID7272 2017-02-27 15:37:29,052 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '22.6', 'stats_rootsieve_time': 
'21.31'}
PID7272 2017-02-27 15:37:29,052 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '54.87', 'stats_rootsieve_time': '51.67'}
PID7272 2017-02-27 15:37:29,053 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_6 as ok (70.0% => ETA Mon Feb 27 15:37:36 
2017)
PID7272 2017-02-27 15:37:29,091 Info:Polynomial Selection (root optimized): 
Best polynomial from file 
/home/user/dlp/60_ok/p60.upload/p60.polyselect2.7iqmfw8i.opt_12 with 
E=2.11e-06 is no better than current best with E=2.67e-06
PID7272 2017-02-27 15:37:29,092 Debug:Polynomial Selection (root optimized): 
Newly arrived stats: {'stats_total_time': '23.92', 'stats_rootsieve_time': 
'22.68'}
PID7272 2017-02-27 15:37:29,092 Debug:Polynomial Selection (root optimized): 
Combined stats: {'stats_total_time': '78.78999999999999', 
'stats_rootsieve_time': '74.35'}
PID7272 2017-02-27 15:37:29,094 Info:Polynomial Selection (root optimized): 
Marking workunit p60_polyselect2_12 as ok (100.0% => ETA Mon Feb 27 15:37:29 
2017)
PID7272 2017-02-27 15:37:29,100 Info:Polynomial Selection (root optimized): 
Finished, best polynomial from file 
/home/user/dlp/60_ok/p60.upload/p60.polyselect2.4omecehb.opt_6 has Murphy_E = 
2.67e-06
PID7272 2017-02-27 15:37:29,100 Info:Polynomial Selection (root optimized): 
Best overall polynomial was 13-th in list after size optimization
PID7272 2017-02-27 15:37:29,128 Info:Polynomial Selection (root optimized): 
Aggregate statistics:
PID7272 2017-02-27 15:37:29,129 Info:Polynomial Selection (root optimized): 
Total time: 78.79
PID7272 2017-02-27 15:37:29,129 Info:Polynomial Selection (root optimized): 
Rootsieve time: 74.35
PID7272 2017-02-27 15:37:29,129 Info:Number Theory for DLP: Starting
PID7272 2017-02-27 15:37:29,129 Debug:Number Theory for DLP: 
numbertheory.run(): Task state: {}
PID7272 2017-02-27 15:37:29,229 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/badideals -poly /home/user/dlp/60_ok/p60.poly 
-ell 157079632679489661923132169163975144209858469968755291072921 
-badidealinfo /home/user/dlp/60_ok/p60.badidealinfo -badideals 
/home/user/dlp/60_ok/p60.badideals > 
/home/user/dlp/60_ok/p60.badideals.stdout.1 2> 
/home/user/dlp/60_ok/p60.badideals.stderr.1
PID7272 2017-02-27 15:37:29,352 Debug:Command: Process with PID 7330 finished 
successfully
PID7272 2017-02-27 15:37:29,361 Debug:Number Theory for DLP: Exit 
NumberTheoryTask.run(numbertheory)
PID7272 2017-02-27 15:37:29,361 Info:Number Theory for DLP: Total cpu/real 
time for badideals: 0.12/0.122789
PID7272 2017-02-27 15:37:29,361 Info:Generate Factor Base: Starting
PID7272 2017-02-27 15:37:29,361 Debug:Generate Factor Base: factorbase.run(): 
Task state: {}
PID7272 2017-02-27 15:37:29,449 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/makefb -poly /home/user/dlp/60_ok/p60.poly 
-lim 600000 -maxbits 11 -out /home/user/dlp/60_ok/p60.roots.gz -t 10 > 
/home/user/dlp/60_ok/p60.makefb.stdout.1 2> 
/home/user/dlp/60_ok/p60.makefb.stderr.1
PID7272 2017-02-27 15:37:29,847 Debug:Command: Process with PID 7331 finished 
successfully
PID7272 2017-02-27 15:37:29,856 Info:Generate Factor Base: Finished
PID7272 2017-02-27 15:37:29,856 Info:Generate Factor Base: Total cpu/real 
time for makefb: 2.04/0.397201
PID7272 2017-02-27 15:37:29,856 Info:Generate Free Relations: Starting
PID7272 2017-02-27 15:37:29,856 Debug:Generate Free Relations: freerel.run(): 
Task state: {}
PID7272 2017-02-27 15:37:29,876 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/freerel -poly 
/home/user/dlp/60_ok/p60.poly -renumber /home/user/dlp/60_ok/p60.renumber.gz 
-lpb0 22 -lpb1 22 -out /home/user/dlp/60_ok/p60.freerel.gz -badideals 
/home/user/dlp/60_ok/p60.badideals -lcideals -t 10 > 
/home/user/dlp/60_ok/p60.freerel.stdout.1 2> 
/home/user/dlp/60_ok/p60.freerel.stderr.1
PID7272 2017-02-27 15:37:31,746 Debug:Command: Process with PID 7382 finished 
successfully
PID7272 2017-02-27 15:37:31,756 Info:Generate Free Relations: Found 12307 
free relations
PID7272 2017-02-27 15:37:31,756 Info:Generate Free Relations: Finished
PID7272 2017-02-27 15:37:31,756 Info:Generate Free Relations: Total cpu/real 
time for freerel: 15.47/1.8698
PID7272 2017-02-27 15:37:31,756 Info:Lattice Sieving: Starting
PID7272 2017-02-27 15:37:31,757 Debug:Lattice Sieving: sieving.run(): Task 
state: {'rels_found': 0, 'rels_wanted': 1000000, 'start_achievement': -1, 
'wu_failed': 0, 'wu_received': 0, 'qnext': 200000, 'start_real_time': 0, 
'wu_timedout': 0, 'wu_submitted': 0}
PID7272 2017-02-27 15:37:31,757 Info:Lattice Sieving: We want 1000000 
relations
PID7272 2017-02-27 15:37:31,759 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.roots.gz
PID7272 2017-02-27 15:37:31,760 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.roots.gz is 
('8f7e03980ee62012c3a3ed9d2aedc74edafcbe70', (41731, 262693, 
1488199049.8455281))
PID7272 2017-02-27 15:37:31,760 Debug:Sha1Cache: Computing SHA1 for file 
/home/user/dlp/60_ok/p60.poly
PID7272 2017-02-27 15:37:31,761 Debug:Sha1Cache: SHA1 for file 
/home/user/dlp/60_ok/p60.poly is ('57b857bed68213ef2dd8d1aa8ddd13fc230f6b8b', 
(12273521, 369, 1488199049.1005282))
PID7272 2017-02-27 15:37:31,761 Debug:Sha1Cache: Computing SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID7272 2017-02-27 15:37:31,774 Debug:Sha1Cache: SHA1 for file 
$CADO_DIR/lib/cado-nfs-2.2.1/sieve/las is 
('b5e4c2cf4e9948b30ac22d8db2f62260a17d93ab', (205121301, 6622258, 
1488182379.0))
PID7272 2017-02-27 15:37:31,775 Debug:Server Launcher: Registering file name 
las with target $CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID7272 2017-02-27 15:37:31,781 Debug:Server Launcher: Registering file name 
p60.roots.gz with target /home/user/dlp/60_ok/p60.roots.gz
PID7272 2017-02-27 15:37:31,788 Debug:Server Launcher: Registering file name 
p60.poly with target /home/user/dlp/60_ok/p60.poly
PID7272 2017-02-27 15:37:31,794 Info:Lattice Sieving: Adding workunit 
p60_sieving_200000-201000 to database
PID7272 2017-02-27 15:37:31,807 Info:Lattice Sieving: Adding workunit 
p60_sieving_201000-202000 to database
PID7272 2017-02-27 15:37:31,814 Info:Lattice Sieving: Adding workunit 
p60_sieving_202000-203000 to database
PID7272 2017-02-27 15:37:31,820 Info:Lattice Sieving: Adding workunit 
p60_sieving_203000-204000 to database
PID7272 2017-02-27 15:37:31,827 Info:Lattice Sieving: Adding workunit 
p60_sieving_204000-205000 to database
PID7272 2017-02-27 15:37:31,835 Info:Lattice Sieving: Adding workunit 
p60_sieving_205000-206000 to database
PID7272 2017-02-27 15:37:31,841 Info:Lattice Sieving: Adding workunit 
p60_sieving_206000-207000 to database
PID7272 2017-02-27 15:37:31,854 Info:Lattice Sieving: Adding workunit 
p60_sieving_207000-208000 to database
PID7272 2017-02-27 15:37:31,866 Info:Lattice Sieving: Adding workunit 
p60_sieving_208000-209000 to database
PID7272 2017-02-27 15:37:31,874 Info:Lattice Sieving: Adding workunit 
p60_sieving_209000-210000 to database
PID7272 2017-02-27 15:37:31,881 Debug:Lattice Sieving: Setting last timeout 
check to 1488199051.881072
PID7272 2017-02-27 15:37:36,986 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_200000-201000 to client localhost
PID7272 2017-02-27 15:37:36,986 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:37,183 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_201000-202000 to client localhost+2
PID7272 2017-02-27 15:37:37,184 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:37,185 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.roots.gz to /home/user/dlp/60_ok/p60.roots.gz
PID7272 2017-02-27 15:37:37,185 Debug:HTTP server: 127.0.0.1 "GET 
/p60.roots.gz HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,186 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.roots.gz to /home/user/dlp/60_ok/p60.roots.gz
PID7272 2017-02-27 15:37:37,186 Debug:HTTP server: 127.0.0.1 "GET 
/p60.roots.gz HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,187 Warning:HTTP server: 127.0.0.1 Connection 
error: [Errno 104] Connection reset by peer
PID7272 2017-02-27 15:37:37,188 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.poly to /home/user/dlp/60_ok/p60.poly
PID7272 2017-02-27 15:37:37,188 Debug:HTTP server: 127.0.0.1 "GET /p60.poly 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,188 Debug:HTTP server: 127.0.0.1 Translated path 
/p60.poly to /home/user/dlp/60_ok/p60.poly
PID7272 2017-02-27 15:37:37,189 Debug:HTTP server: 127.0.0.1 "GET /p60.poly 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,190 Debug:HTTP server: 127.0.0.1 Translated path 
/las to $CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID7272 2017-02-27 15:37:37,190 Debug:HTTP server: 127.0.0.1 "GET /las 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,198 Debug:HTTP server: 127.0.0.1 Translated path 
/las to $CADO_DIR/lib/cado-nfs-2.2.1/sieve/las
PID7272 2017-02-27 15:37:37,198 Debug:HTTP server: 127.0.0.1 "GET /las 
HTTP/1.1" HTTPStatus.OK -
PID7272 2017-02-27 15:37:37,199 Warning:HTTP server: 127.0.0.1 Connection 
error: [Errno 104] Connection reset by peer
PID7272 2017-02-27 15:37:37,900 Info:Lattice Sieving: Adding workunit 
p60_sieving_210000-211000 to database
PID7272 2017-02-27 15:37:38,014 Info:Lattice Sieving: Adding workunit 
p60_sieving_211000-212000 to database
PID7272 2017-02-27 15:37:38,325 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_202000-203000 to client localhost+3
PID7272 2017-02-27 15:37:38,326 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:38,479 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_203000-204000 to client localhost+4
PID7272 2017-02-27 15:37:38,479 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:38,956 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_204000-205000 to client localhost+5
PID7272 2017-02-27 15:37:38,957 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:37:39,128 Info:Lattice Sieving: Adding workunit 
p60_sieving_212000-213000 to database
PID7272 2017-02-27 15:37:39,269 Info:Lattice Sieving: Adding workunit 
p60_sieving_213000-214000 to database
PID7272 2017-02-27 15:37:39,685 Info:Lattice Sieving: Adding workunit 
p60_sieving_214000-215000 to database
PID7272 2017-02-27 15:38:15,394 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:15,398 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:38:16,170 Debug:Lattice Sieving: stderr is: b"# Average 
J=984 for 69 special-q's, max bucket fill 0.661329\n# Discarded 0 special-q's 
out of 69 pushed\n# Total cpu time 35.71s [norm 0.04+0.7, sieving 22.2 (16.7 
+ 1.5 + 4.0), factor 12.7 (12.5 + 0.3)]\n# Total elapsed time 37.91s, per 
special-q 0.549358s, per relation 0.000801644s\n# PeakMemusage (MB) = 414 \n# 
Total 47285 reports [0.000755s/r, 685.3r/sq]\n"
PID7272 2017-02-27 15:38:16,177 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.661329', 'stats_total_cpu_time': '35.71', 
'stats_avg_J': '984.0 69'}
PID7272 2017-02-27 15:38:16,178 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.661329', 'stats_total_cpu_time': '35.71', 
'stats_avg_J': '984.0 69'}
PID7272 2017-02-27 15:38:16,178 Info:Lattice Sieving: Found 47285 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.201000-202000.lkyvdov9.gz', total is 
now 47285/1000000
PID7272 2017-02-27 15:38:16,405 Info:Lattice Sieving: Marking workunit 
p60_sieving_201000-202000 as ok (4.7% => ETA Unknown)
PID7272 2017-02-27 15:38:16,437 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:16,437 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:38:16,666 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_205000-206000 to client localhost+2
PID7272 2017-02-27 15:38:16,667 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:16,697 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_206000-207000 to client localhost+4
PID7272 2017-02-27 15:38:16,697 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:17,494 Info:Lattice Sieving: Adding workunit 
p60_sieving_215000-216000 to database
PID7272 2017-02-27 15:38:17,728 Info:Lattice Sieving: Adding workunit 
p60_sieving_216000-217000 to database
PID7272 2017-02-27 15:38:17,805 Debug:Lattice Sieving: stderr is: b"# Average 
J=966 for 66 special-q's, max bucket fill 0.627742\n# Discarded 0 special-q's 
out of 66 pushed\n# Total cpu time 34.85s [norm 0.04+0.7, sieving 21.6 (16.4 
+ 1.4 + 3.7), factor 12.5 (12.3 + 0.2)]\n# Total elapsed time 37.61s, per 
special-q 0.569922s, per relation 0.000848252s\n# PeakMemusage (MB) = 415 \n# 
Total 44344 reports [0.000786s/r, 671.9r/sq]\n"
PID7272 2017-02-27 15:38:17,821 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.627742', 'stats_total_cpu_time': '34.85', 
'stats_avg_J': '966.0 66'}
PID7272 2017-02-27 15:38:17,822 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.661329', 'stats_total_cpu_time': '70.56', 
'stats_avg_J': '975.2 135'}
PID7272 2017-02-27 15:38:17,823 Info:Lattice Sieving: Found 44344 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.203000-204000.6voz0gdv.gz', total is 
now 91629/1000000
PID7272 2017-02-27 15:38:17,824 Info:Lattice Sieving: Marking workunit 
p60_sieving_203000-204000 as ok (9.2% => ETA Mon Feb 27 15:54:00 2017)
PID7272 2017-02-27 15:38:19,289 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:19,297 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:38:19,960 Debug:Lattice Sieving: stderr is: b"# Average 
J=962 for 73 special-q's, max bucket fill 0.624303\n# Discarded 0 special-q's 
out of 73 pushed\n# Total cpu time 38.29s [norm 0.05+0.7, sieving 23.7 (18.0 
+ 1.6 + 4.1), factor 13.8 (13.5 + 0.3)]\n# Total elapsed time 40.64s, per 
special-q 0.556739s, per relation 0.000854037s\n# PeakMemusage (MB) = 414 \n# 
Total 47588 reports [0.000805s/r, 651.9r/sq]\n"
PID7272 2017-02-27 15:38:19,970 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.624303', 'stats_total_cpu_time': '38.29', 
'stats_avg_J': '962.0 73'}
PID7272 2017-02-27 15:38:19,970 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.661329', 'stats_total_cpu_time': '108.85', 
'stats_avg_J': '970.5673076923077 208'}
PID7272 2017-02-27 15:38:19,972 Info:Lattice Sieving: Found 47588 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.202000-203000.i6ofs663.gz', total is 
now 139217/1000000
PID7272 2017-02-27 15:38:19,973 Info:Lattice Sieving: Marking workunit 
p60_sieving_202000-203000 as ok (13.9% => ETA Mon Feb 27 15:45:51 2017)
PID7272 2017-02-27 15:38:22,118 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_207000-208000 to client localhost+3
PID7272 2017-02-27 15:38:22,121 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:22,182 Info:Lattice Sieving: Adding workunit 
p60_sieving_217000-218000 to database
PID7272 2017-02-27 15:38:25,342 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:25,343 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:38:27,143 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:27,147 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:38:27,293 Debug:Lattice Sieving: stderr is: b"# Average 
J=984 for 87 special-q's, max bucket fill 0.672919\n# Discarded 0 special-q's 
out of 87 pushed\n# Total cpu time 44.63s [norm 0.06+0.9, sieving 27.6 (20.9 
+ 1.8 + 4.9), factor 16.1 (15.8 + 0.3)]\n# Total elapsed time 47.87s, per 
special-q 0.550255s, per relation 0.000805874s\n# PeakMemusage (MB) = 414 \n# 
Total 59404 reports [0.000751s/r, 682.8r/sq]\n"
PID7272 2017-02-27 15:38:27,298 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.672919', 'stats_total_cpu_time': '44.63', 
'stats_avg_J': '984.0 87'}
PID7272 2017-02-27 15:38:27,302 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.672919', 'stats_total_cpu_time': '153.48', 
'stats_avg_J': '974.5288135593221 295'}
PID7272 2017-02-27 15:38:27,303 Info:Lattice Sieving: Found 59404 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.200000-201000.d7_ojt8p.gz', total is 
now 198621/1000000
PID7272 2017-02-27 15:38:27,306 Info:Lattice Sieving: Marking workunit 
p60_sieving_200000-201000 as ok (19.9% => ETA Mon Feb 27 15:43:21 2017)
PID7272 2017-02-27 15:38:27,506 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_208000-209000 to client localhost
PID7272 2017-02-27 15:38:27,506 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:27,524 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_209000-210000 to client localhost+5
PID7272 2017-02-27 15:38:27,525 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:28,413 Info:Lattice Sieving: Adding workunit 
p60_sieving_218000-219000 to database
PID7272 2017-02-27 15:38:28,487 Info:Lattice Sieving: Adding workunit 
p60_sieving_219000-220000 to database
PID7272 2017-02-27 15:38:28,554 Debug:Lattice Sieving: stderr is: b"# Average 
J=974 for 81 special-q's, max bucket fill 0.650646\n# Discarded 0 special-q's 
out of 81 pushed\n# Total cpu time 42.57s [norm 0.07+0.8, sieving 26.4 (20.1 
+ 1.8 + 4.6), factor 15.2 (14.9 + 0.3)]\n# Total elapsed time 46.19s, per 
special-q 0.570201s, per relation 0.000858194s\n# PeakMemusage (MB) = 417 \n# 
Total 53818 reports [0.000791s/r, 664.4r/sq]\n"
PID7272 2017-02-27 15:38:28,557 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.650646', 'stats_total_cpu_time': '42.57', 
'stats_avg_J': '974.0 81'}
PID7272 2017-02-27 15:38:28,558 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.672919', 'stats_total_cpu_time': 
'196.04999999999998', 'stats_avg_J': '974.4148936170212 376'}
PID7272 2017-02-27 15:38:28,558 Info:Lattice Sieving: Found 53818 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.204000-205000.6lsu7922.gz', total is 
now 252439/1000000
PID7272 2017-02-27 15:38:28,558 Info:Lattice Sieving: Marking workunit 
p60_sieving_204000-205000 as ok (25.2% => ETA Mon Feb 27 15:41:55 2017)
PID7272 2017-02-27 15:38:56,143 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:38:56,145 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:01,267 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:01,267 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:01,832 Debug:Lattice Sieving: stderr is: b"# Average 
J=995 for 79 special-q's, max bucket fill 0.632599\n# Discarded 0 special-q's 
out of 79 pushed\n# Total cpu time 42.19s [norm 0.05+0.9, sieving 25.8 (19.6 
+ 1.6 + 4.6), factor 15.5 (15.2 + 0.3)]\n# Total elapsed time 43.44s, per 
special-q 0.549836s, per relation 0.000798754s\n# PeakMemusage (MB) = 414 \n# 
Total 54381 reports [0.000776s/r, 688.4r/sq]\n"
PID7272 2017-02-27 15:39:01,849 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_210000-211000 to client localhost+4
PID7272 2017-02-27 15:39:01,849 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:01,855 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.632599', 'stats_total_cpu_time': '42.19', 
'stats_avg_J': '995.0 79'}
PID7272 2017-02-27 15:39:01,856 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.672919', 'stats_total_cpu_time': 
'238.23999999999998', 'stats_avg_J': '977.989010989011 455'}
PID7272 2017-02-27 15:39:01,871 Info:Lattice Sieving: Found 54381 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.205000-206000.d45ms2o5.gz', total is 
now 306820/1000000
PID7272 2017-02-27 15:39:01,873 Info:Lattice Sieving: Marking workunit 
p60_sieving_205000-206000 as ok (30.7% => ETA Mon Feb 27 15:43:02 2017)
PID7272 2017-02-27 15:39:02,488 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_211000-212000 to client localhost+2
PID7272 2017-02-27 15:39:02,489 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:02,821 Info:Lattice Sieving: Adding workunit 
p60_sieving_220000-221000 to database
PID7272 2017-02-27 15:39:03,763 Info:Lattice Sieving: Adding workunit 
p60_sieving_221000-222000 to database
PID7272 2017-02-27 15:39:05,548 Debug:Lattice Sieving: stderr is: b"# Average 
J=967 for 71 special-q's, max bucket fill 0.733175\n# Discarded 0 special-q's 
out of 71 pushed\n# Total cpu time 36.27s [norm 0.06+0.6, sieving 22.8 (17.3 
+ 1.5 + 4.0), factor 12.8 (12.6 + 0.2)]\n# Total elapsed time 39.17s, per 
special-q 0.551741s, per relation 0.000827897s\n# PeakMemusage (MB) = 415 \n# 
Total 47317 reports [0.000767s/r, 666.4r/sq]\n"
PID7272 2017-02-27 15:39:05,550 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '36.27', 
'stats_avg_J': '967.0 71'}
PID7272 2017-02-27 15:39:05,550 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '274.51', 
'stats_avg_J': '976.5057034220532 526'}
PID7272 2017-02-27 15:39:05,552 Info:Lattice Sieving: Found 47317 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.206000-207000.w5teksj1.gz', total is 
now 354137/1000000
PID7272 2017-02-27 15:39:05,552 Info:Lattice Sieving: Marking workunit 
p60_sieving_206000-207000 as ok (35.4% => ETA Mon Feb 27 15:42:22 2017)
PID7272 2017-02-27 15:39:12,361 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:12,361 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:12,960 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:12,960 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:13,644 Debug:Lattice Sieving: stderr is: b"# Average 
J=970 for 94 special-q's, max bucket fill 0.639207\n# Discarded 0 special-q's 
out of 94 pushed\n# Total cpu time 48.60s [norm 0.04+0.9, sieving 30.0 (22.7 
+ 1.9 + 5.4), factor 17.7 (17.4 + 0.3)]\n# Total elapsed time 50.35s, per 
special-q 0.535664s, per relation 0.000816575s\n# PeakMemusage (MB) = 414 \n# 
Total 61663 reports [0.000788s/r, 656.0r/sq]\n"
PID7272 2017-02-27 15:39:13,646 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.639207', 'stats_total_cpu_time': '48.6', 
'stats_avg_J': '970.0 94'}
PID7272 2017-02-27 15:39:13,647 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '323.11', 
'stats_avg_J': '975.5193548387097 620'}
PID7272 2017-02-27 15:39:13,647 Info:Lattice Sieving: Found 61663 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.207000-208000._j2itdt8.gz', total is 
now 415800/1000000
PID7272 2017-02-27 15:39:13,648 Info:Lattice Sieving: Marking workunit 
p60_sieving_207000-208000 as ok (41.6% => ETA Mon Feb 27 15:41:55 2017)
PID7272 2017-02-27 15:39:13,919 Debug:Lattice Sieving: stderr is: b"# Average 
J=965 for 81 special-q's, max bucket fill 0.632345\n# Discarded 0 special-q's 
out of 81 pushed\n# Total cpu time 42.88s [norm 0.04+0.8, sieving 26.4 (20.1 
+ 1.7 + 4.6), factor 15.7 (15.4 + 0.3)]\n# Total elapsed time 44.54s, per 
special-q 0.5499s, per relation 0.000831068s\n# PeakMemusage (MB) = 417 \n# 
Total 53596 reports [0.0008s/r, 661.7r/sq]\n"
PID7272 2017-02-27 15:39:13,922 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.632345', 'stats_total_cpu_time': '42.88', 
'stats_avg_J': '965.0 81'}
PID7272 2017-02-27 15:39:13,922 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '365.99', 
'stats_avg_J': '974.3038516405136 701'}
PID7272 2017-02-27 15:39:13,923 Info:Lattice Sieving: Found 53596 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.208000-209000.i0tgz5en.gz', total is 
now 469396/1000000
PID7272 2017-02-27 15:39:13,923 Info:Lattice Sieving: Marking workunit 
p60_sieving_208000-209000 as ok (46.9% => ETA Mon Feb 27 15:41:22 2017)
PID7272 2017-02-27 15:39:16,200 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_212000-213000 to client localhost
PID7272 2017-02-27 15:39:16,200 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:16,202 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:16,202 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:16,456 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_213000-214000 to client localhost+5
PID7272 2017-02-27 15:39:16,457 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:16,997 Info:Lattice Sieving: Adding workunit 
p60_sieving_222000-223000 to database
PID7272 2017-02-27 15:39:17,222 Info:Lattice Sieving: Adding workunit 
p60_sieving_223000-224000 to database
PID7272 2017-02-27 15:39:17,515 Debug:Lattice Sieving: stderr is: b"# Average 
J=987 for 91 special-q's, max bucket fill 0.677052\n# Discarded 0 special-q's 
out of 91 pushed\n# Total cpu time 46.68s [norm 0.04+1.0, sieving 28.9 (22.1 
+ 1.9 + 5.0), factor 16.8 (16.4 + 0.3)]\n# Total elapsed time 48.20s, per 
special-q 0.529721s, per relation 0.000775655s\n# PeakMemusage (MB) = 414 \n# 
Total 62147 reports [0.000751s/r, 682.9r/sq]\n"
PID7272 2017-02-27 15:39:17,524 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.677052', 'stats_total_cpu_time': '46.68', 
'stats_avg_J': '987.0 91'}
PID7272 2017-02-27 15:39:17,524 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '412.67', 
'stats_avg_J': '975.7626262626262 792'}
PID7272 2017-02-27 15:39:17,527 Info:Lattice Sieving: Found 62147 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.209000-210000.mub82wif.gz', total is 
now 531543/1000000
PID7272 2017-02-27 15:39:17,529 Info:Lattice Sieving: Marking workunit 
p60_sieving_209000-210000 as ok (53.2% => ETA Mon Feb 27 15:40:59 2017)
PID7272 2017-02-27 15:39:18,736 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_214000-215000 to client localhost+3
PID7272 2017-02-27 15:39:18,739 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:19,602 Info:Lattice Sieving: Adding workunit 
p60_sieving_224000-225000 to database
PID7272 2017-02-27 15:39:46,719 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:46,721 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:47,130 Debug:Lattice Sieving: stderr is: b"# Average 
J=950 for 85 special-q's, max bucket fill 0.634878\n# Discarded 0 special-q's 
out of 85 pushed\n# Total cpu time 42.14s [norm 0.05+0.8, sieving 26.2 (19.9 
+ 1.8 + 4.6), factor 15.0 (14.8 + 0.3)]\n# Total elapsed time 44.32s, per 
special-q 0.521393s, per relation 0.000800246s\n# PeakMemusage (MB) = 417 \n# 
Total 55381 reports [0.000761s/r, 651.5r/sq]\n"
PID7272 2017-02-27 15:39:47,133 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.634878', 'stats_total_cpu_time': '42.14', 
'stats_avg_J': '950.0 85'}
PID7272 2017-02-27 15:39:47,133 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '454.81', 
'stats_avg_J': '973.2656784492589 877'}
PID7272 2017-02-27 15:39:47,135 Info:Lattice Sieving: Found 55381 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.210000-211000.mygh_1ru.gz', total is 
now 586924/1000000
PID7272 2017-02-27 15:39:47,135 Info:Lattice Sieving: Marking workunit 
p60_sieving_210000-211000 as ok (58.7% => ETA Mon Feb 27 15:41:30 2017)
PID7272 2017-02-27 15:39:47,563 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:47,568 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:48,225 Debug:Lattice Sieving: stderr is: b"# Average 
J=976 for 86 special-q's, max bucket fill 0.670987\n# Discarded 0 special-q's 
out of 86 pushed\n# Total cpu time 43.22s [norm 0.03+0.8, sieving 27.0 (20.6 
+ 1.7 + 4.7), factor 15.4 (15.1 + 0.3)]\n# Total elapsed time 44.61s, per 
special-q 0.518725s, per relation 0.000772755s\n# PeakMemusage (MB) = 356 \n# 
Total 57729 reports [0.000749s/r, 671.3r/sq]\n"
PID7272 2017-02-27 15:39:48,229 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.670987', 'stats_total_cpu_time': '43.22', 
'stats_avg_J': '976.0 86'}
PID7272 2017-02-27 15:39:48,229 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '498.03', 
'stats_avg_J': '973.5098650051921 963'}
PID7272 2017-02-27 15:39:48,230 Info:Lattice Sieving: Found 57729 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.211000-212000.b55spyd3.gz', total is 
now 644653/1000000
PID7272 2017-02-27 15:39:48,230 Info:Lattice Sieving: Marking workunit 
p60_sieving_211000-212000 as ok (64.5% => ETA Mon Feb 27 15:41:09 2017)
PID7272 2017-02-27 15:39:49,411 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_215000-216000 to client localhost+4
PID7272 2017-02-27 15:39:49,412 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:50,394 Info:Lattice Sieving: Adding workunit 
p60_sieving_225000-226000 to database
PID7272 2017-02-27 15:39:50,612 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_216000-217000 to client localhost+2
PID7272 2017-02-27 15:39:50,612 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:51,554 Info:Lattice Sieving: Adding workunit 
p60_sieving_226000-227000 to database
PID7272 2017-02-27 15:39:56,303 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:56,305 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:39:57,022 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_217000-218000 to client localhost
PID7272 2017-02-27 15:39:57,023 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:39:57,758 Info:Lattice Sieving: Adding workunit 
p60_sieving_227000-228000 to database
PID7272 2017-02-27 15:39:57,907 Debug:Lattice Sieving: stderr is: b"# Average 
J=979 for 70 special-q's, max bucket fill 0.619111\n# Discarded 0 special-q's 
out of 70 pushed\n# Total cpu time 36.62s [norm 0.05+0.7, sieving 22.7 (17.2 
+ 1.5 + 4.0), factor 13.2 (12.9 + 0.3)]\n# Total elapsed time 37.79s, per 
special-q 0.539838s, per relation 0.000810151s\n# PeakMemusage (MB) = 414 \n# 
Total 46644 reports [0.000785s/r, 666.3r/sq]\n"
PID7272 2017-02-27 15:39:57,914 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.619111', 'stats_total_cpu_time': '36.62', 
'stats_avg_J': '979.0 70'}
PID7272 2017-02-27 15:39:57,915 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '534.65', 
'stats_avg_J': '973.8818973862536 1033'}
PID7272 2017-02-27 15:39:57,919 Info:Lattice Sieving: Found 46644 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.212000-213000.fh3jasvv.gz', total is 
now 691297/1000000
PID7272 2017-02-27 15:39:57,919 Info:Lattice Sieving: Marking workunit 
p60_sieving_212000-213000 as ok (69.1% => ETA Mon Feb 27 15:41:07 2017)
PID7272 2017-02-27 15:40:02,916 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:02,916 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:03,647 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_218000-219000 to client localhost+5
PID7272 2017-02-27 15:40:03,649 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:04,078 Info:Lattice Sieving: Adding workunit 
p60_sieving_228000-229000 to database
PID7272 2017-02-27 15:40:04,473 Debug:Lattice Sieving: stderr is: b"# Average 
J=1012 for 82 special-q's, max bucket fill 0.590742\n# Discarded 0 
special-q's out of 82 pushed\n# Total cpu time 44.29s [norm 0.05+0.9, sieving 
27.1 (20.7 + 1.8 + 4.6), factor 16.2 (15.9 + 0.3)]\n# Total elapsed time 
46.18s, per special-q 0.563191s, per relation 0.000812814s\n# PeakMemusage 
(MB) = 417 \n# Total 56817 reports [0.00078s/r, 692.9r/sq]\n"
PID7272 2017-02-27 15:40:04,480 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.590742', 'stats_total_cpu_time': '44.29', 
'stats_avg_J': '1012.0 82'}
PID7272 2017-02-27 15:40:04,481 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': 
'578.9399999999999', 'stats_avg_J': '976.685201793722 1115'}
PID7272 2017-02-27 15:40:04,482 Info:Lattice Sieving: Found 56817 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.213000-214000.11onttd3.gz', total is 
now 748114/1000000
PID7272 2017-02-27 15:40:04,484 Info:Lattice Sieving: Marking workunit 
p60_sieving_213000-214000 as ok (74.8% => ETA Mon Feb 27 15:40:59 2017)
PID7272 2017-02-27 15:40:05,256 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:05,261 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:06,645 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_219000-220000 to client localhost+3
PID7272 2017-02-27 15:40:06,648 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:06,666 Info:Lattice Sieving: Adding workunit 
p60_sieving_229000-230000 to database
PID7272 2017-02-27 15:40:07,439 Debug:Lattice Sieving: stderr is: b"# Average 
J=977 for 82 special-q's, max bucket fill 0.620077\n# Discarded 0 special-q's 
out of 82 pushed\n# Total cpu time 42.58s [norm 0.04+0.8, sieving 26.3 (20.1 
+ 1.7 + 4.5), factor 15.4 (15.1 + 0.3)]\n# Total elapsed time 45.43s, per 
special-q 0.55398s, per relation 0.000845095s\n# PeakMemusage (MB) = 417 \n# 
Total 53753 reports [0.000792s/r, 655.5r/sq]\n"
PID7272 2017-02-27 15:40:07,442 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.620077', 'stats_total_cpu_time': '42.58', 
'stats_avg_J': '977.0 82'}
PID7272 2017-02-27 15:40:07,442 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '621.52', 
'stats_avg_J': '976.7067669172932 1197'}
PID7272 2017-02-27 15:40:07,444 Info:Lattice Sieving: Found 53753 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.214000-215000.lvrz_fqe.gz', total is 
now 801867/1000000
PID7272 2017-02-27 15:40:07,444 Info:Lattice Sieving: Marking workunit 
p60_sieving_214000-215000 as ok (80.2% => ETA Mon Feb 27 15:40:48 2017)
PID7272 2017-02-27 15:40:31,976 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:31,979 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:33,182 Debug:Lattice Sieving: stderr is: b"# Average 
J=992 for 71 special-q's, max bucket fill 0.648240\n# Discarded 0 special-q's 
out of 71 pushed\n# Total cpu time 38.38s [norm 0.04+0.8, sieving 23.6 (18.0 
+ 1.6 + 4.0), factor 13.9 (13.7 + 0.2)]\n# Total elapsed time 41.01s, per 
special-q 0.577638s, per relation 0.000853996s\n# PeakMemusage (MB) = 414 \n# 
Total 48024 reports [0.000799s/r, 676.4r/sq]\n"
PID7272 2017-02-27 15:40:33,463 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_220000-221000 to client localhost+2
PID7272 2017-02-27 15:40:33,468 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:33,475 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:33,477 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:33,597 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.64824', 'stats_total_cpu_time': '38.38', 
'stats_avg_J': '992.0 71'}
PID7272 2017-02-27 15:40:33,612 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '659.9', 
'stats_avg_J': '977.5630914826498 1268'}
PID7272 2017-02-27 15:40:33,645 Info:Lattice Sieving: Found 48024 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.216000-217000.9niigw3g.gz', total is 
now 849891/1000000
PID7272 2017-02-27 15:40:33,647 Info:Lattice Sieving: Marking workunit 
p60_sieving_216000-217000 as ok (85.0% => ETA Mon Feb 27 15:41:07 2017)
PID7272 2017-02-27 15:40:34,049 Info:Lattice Sieving: Adding workunit 
p60_sieving_230000-231000 to database
PID7272 2017-02-27 15:40:35,387 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_221000-222000 to client localhost+4
PID7272 2017-02-27 15:40:35,388 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:35,540 Info:Lattice Sieving: Adding workunit 
p60_sieving_231000-232000 to database
PID7272 2017-02-27 15:40:35,762 Debug:Lattice Sieving: stderr is: b"# Average 
J=973 for 74 special-q's, max bucket fill 0.708903\n# Discarded 0 special-q's 
out of 74 pushed\n# Total cpu time 39.82s [norm 0.06+0.7, sieving 24.7 (18.7 
+ 1.7 + 4.4), factor 14.3 (14.1 + 0.3)]\n# Total elapsed time 43.05s, per 
special-q 0.581766s, per relation 0.000873114s\n# PeakMemusage (MB) = 414 \n# 
Total 49307 reports [0.000808s/r, 666.3r/sq]\n"
PID7272 2017-02-27 15:40:35,768 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.708903', 'stats_total_cpu_time': '39.82', 
'stats_avg_J': '973.0 74'}
PID7272 2017-02-27 15:40:35,768 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '699.72', 
'stats_avg_J': '977.311475409836 1342'}
PID7272 2017-02-27 15:40:35,770 Info:Lattice Sieving: Found 49307 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.215000-216000.k3e1oyhh.gz', total is 
now 899198/1000000
PID7272 2017-02-27 15:40:35,771 Info:Lattice Sieving: Marking workunit 
p60_sieving_215000-216000 as ok (89.9% => ETA Mon Feb 27 15:40:57 2017)
PID7272 2017-02-27 15:40:42,159 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:42,162 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:42,605 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_222000-223000 to client localhost
PID7272 2017-02-27 15:40:42,607 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:43,165 Info:Lattice Sieving: Adding workunit 
p60_sieving_232000-233000 to database
PID7272 2017-02-27 15:40:43,302 Debug:Lattice Sieving: stderr is: b"# Average 
J=948 for 77 special-q's, max bucket fill 0.650424\n# Discarded 0 special-q's 
out of 77 pushed\n# Total cpu time 40.68s [norm 0.06+0.8, sieving 25.3 (19.2 
+ 1.7 + 4.4), factor 14.5 (14.3 + 0.3)]\n# Total elapsed time 44.50s, per 
special-q 0.57797s, per relation 0.000885363s\n# PeakMemusage (MB) = 414 \n# 
Total 50266 reports [0.000809s/r, 652.8r/sq]\n"
PID7272 2017-02-27 15:40:43,307 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.650424', 'stats_total_cpu_time': '40.68', 
'stats_avg_J': '948.0 77'}
PID7272 2017-02-27 15:40:43,307 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '740.4', 
'stats_avg_J': '975.7209302325581 1419'}
PID7272 2017-02-27 15:40:43,308 Info:Lattice Sieving: Found 50266 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.217000-218000.072bt7ku.gz', total is 
now 949464/1000000
PID7272 2017-02-27 15:40:43,309 Info:Lattice Sieving: Marking workunit 
p60_sieving_217000-218000 as ok (94.9% => ETA Mon Feb 27 15:40:54 2017)
PID7272 2017-02-27 15:40:56,444 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:56,446 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:40:58,995 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_223000-224000 to client localhost+3
PID7272 2017-02-27 15:40:58,996 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+3 HTTP/1.1" 200 -
PID7272 2017-02-27 15:40:59,587 Info:Lattice Sieving: Adding workunit 
p60_sieving_233000-234000 to database
PID7272 2017-02-27 15:40:59,834 Debug:Lattice Sieving: stderr is: b"# Average 
J=987 for 83 special-q's, max bucket fill 0.687627\n# Discarded 0 special-q's 
out of 83 pushed\n# Total cpu time 45.32s [norm 0.05+0.9, sieving 28.1 (21.4 
+ 1.9 + 4.8), factor 16.2 (15.9 + 0.3)]\n# Total elapsed time 49.35s, per 
special-q 0.594549s, per relation 0.000877447s\n# PeakMemusage (MB) = 415 \n# 
Total 56240 reports [0.000806s/r, 677.6r/sq]\n"
PID7272 2017-02-27 15:40:59,845 Debug:Lattice Sieving: Newly arrived stats: 
{'stats_max_bucket_fill': '0.687627', 'stats_total_cpu_time': '45.32', 
'stats_avg_J': '987.0 83'}
PID7272 2017-02-27 15:40:59,846 Debug:Lattice Sieving: Combined stats: 
{'stats_max_bucket_fill': '0.733175', 'stats_total_cpu_time': '785.72', 
'stats_avg_J': '976.344207723036 1502'}
PID7272 2017-02-27 15:40:59,852 Info:Lattice Sieving: Found 56240 relations 
in '/home/user/dlp/60_ok/p60.upload/p60.219000-220000.z56e9bw5.gz', total is 
now 1005704/1000000
PID7272 2017-02-27 15:40:59,852 Info:Lattice Sieving: Marking workunit 
p60_sieving_219000-220000 as ok (100.6% => ETA Mon Feb 27 15:40:58 2017)
PID7272 2017-02-27 15:41:04,023 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:04,026 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:41:05,203 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_224000-225000 to client localhost+5
PID7272 2017-02-27 15:41:05,204 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+5 HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:05,209 Info:Lattice Sieving: Adding workunit 
p60_sieving_234000-235000 to database
PID7272 2017-02-27 15:41:05,636 Info:Lattice Sieving: Reached target of 
1000000 relations, now have 1005704
PID7272 2017-02-27 15:41:05,640 Debug:Lattice Sieving: Exit 
SievingTask.run(sieving)
PID7272 2017-02-27 15:41:05,641 Info:Lattice Sieving: Aggregate statistics:
PID7272 2017-02-27 15:41:05,641 Info:Lattice Sieving: Total number of 
relations: 1005704
PID7272 2017-02-27 15:41:05,641 Info:Lattice Sieving: Average J: 976.344 for 
1502 special-q, max bucket fill: 0.733175
PID7272 2017-02-27 15:41:05,641 Info:Lattice Sieving: Total CPU time: 785.72s
PID7272 2017-02-27 15:41:05,641 Info:Filtering - Duplicate Removal, splitting 
pass: Starting
PID7272 2017-02-27 15:41:05,641 Debug:Filtering - Duplicate Removal, 
splitting pass: duplicates1.run(): Task state: {}
PID7272 2017-02-27 15:41:05,643 Debug:Filtering - Duplicate Removal, 
splitting pass: new files to split are: 
['/home/user/dlp/60_ok/p60.upload/p60.217000-218000.072bt7ku.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.201000-202000.lkyvdov9.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.206000-207000.w5teksj1.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.204000-205000.6lsu7922.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.202000-203000.i6ofs663.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.210000-211000.mygh_1ru.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.205000-206000.d45ms2o5.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.208000-209000.i0tgz5en.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.214000-215000.lvrz_fqe.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.211000-212000.b55spyd3.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.215000-216000.k3e1oyhh.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.212000-213000.fh3jasvv.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.209000-210000.mub82wif.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.203000-204000.6voz0gdv.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.207000-208000._j2itdt8.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.213000-214000.11onttd3.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.219000-220000.z56e9bw5.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.200000-201000.d7_ojt8p.gz', 
'/home/user/dlp/60_ok/p60.upload/p60.216000-217000.9niigw3g.gz']
PID7272 2017-02-27 15:41:05,643 Info:Filtering - Duplicate Removal, splitting 
pass: Splitting 19 new files
PID7272 2017-02-27 15:41:06,794 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup1 -prefix dup1.0 -out 
/home/user/dlp/60_ok/p60.dup1/ -filelist 
/home/user/dlp/60_ok/p60.dup1.filelist.1 > 
/home/user/dlp/60_ok/p60.dup1.stdout.1 2> 
/home/user/dlp/60_ok/p60.dup1.stderr.1
PID7272 2017-02-27 15:41:17,223 Debug:Command: Process with PID 16494 
finished successfully
PID7272 2017-02-27 15:41:17,277 Debug:Filtering - Duplicate Removal, 
splitting pass: Output file names: 
{'/home/user/dlp/60_ok/p60.dup1//0/dup1.0.0000.gz': 0, 
'/home/user/dlp/60_ok/p60.dup1//1/dup1.0.0000.gz': 1}
PID7272 2017-02-27 15:41:17,279 Debug:Filtering - Duplicate Removal, 
splitting pass: Newly arrived stats: {'stats_dup1_time': '9.4'}
PID7272 2017-02-27 15:41:17,281 Debug:Filtering - Duplicate Removal, 
splitting pass: Combined stats: {'stats_dup1_time': '9.4'}
PID7272 2017-02-27 15:41:17,332 Info:Filtering - Duplicate Removal, splitting 
pass: Relations per slice: 0: 501409, 1: 504295
PID7272 2017-02-27 15:41:17,335 Debug:Filtering - Duplicate Removal, 
splitting pass: Exit Duplicates1Task.run(duplicates1)
PID7272 2017-02-27 15:41:17,336 Info:Filtering - Duplicate Removal, splitting 
pass: Total cpu/real time for dup1: 5.57/10.4294
PID7272 2017-02-27 15:41:17,336 Info:Filtering - Duplicate Removal, splitting 
pass: Aggregate statistics:
PID7272 2017-02-27 15:41:17,336 Info:Filtering - Duplicate Removal, splitting 
pass: CPU time for dup1: 9.4s
PID7272 2017-02-27 15:41:17,336 Info:Filtering - Duplicate Removal, removal 
pass: Starting
PID7272 2017-02-27 15:41:17,336 Debug:Filtering - Duplicate Removal, removal 
pass: duplicates2.run(): Task state: {}
PID7272 2017-02-27 15:41:17,442 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup2 -nrels 501409 -renumber 
/home/user/dlp/60_ok/p60.renumber.gz -badidealinfo 
/home/user/dlp/60_ok/p60.badidealinfo -dl 
/home/user/dlp/60_ok/p60.dup1//0/dup1.0.0000.gz > 
/home/user/dlp/60_ok/p60.dup2.slice0.stdout.1 2> 
/home/user/dlp/60_ok/p60.dup2.slice0.stderr.1
PID7272 2017-02-27 15:41:20,673 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:20,675 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:41:21,424 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_225000-226000 to client localhost+2
PID7272 2017-02-27 15:41:21,426 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+2 HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:23,881 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:23,882 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:41:24,774 Debug:Command: Process with PID 16892 
finished successfully
PID7272 2017-02-27 15:41:25,012 Info:Filtering - Duplicate Removal, removal 
pass: 492849 unique relations remain on slice 0
PID7272 2017-02-27 15:41:25,123 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/dup2 -nrels 504295 -renumber 
/home/user/dlp/60_ok/p60.renumber.gz -badidealinfo 
/home/user/dlp/60_ok/p60.badidealinfo -dl 
/home/user/dlp/60_ok/p60.dup1//1/dup1.0.0000.gz > 
/home/user/dlp/60_ok/p60.dup2.slice1.stdout.1 2> 
/home/user/dlp/60_ok/p60.dup2.slice1.stderr.1
PID7272 2017-02-27 15:41:25,248 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_226000-227000 to client localhost+4
PID7272 2017-02-27 15:41:25,250 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost+4 HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:31,299 Debug:Command: Process with PID 17193 
finished successfully
PID7272 2017-02-27 15:41:31,422 Info:Filtering - Duplicate Removal, removal 
pass: 495717 unique relations remain on slice 1
PID7272 2017-02-27 15:41:31,451 Info:Filtering - Duplicate Removal, removal 
pass: Of 1005704 newly added relations 988566 were unique (ratio 0.982959)
PID7272 2017-02-27 15:41:31,585 Info:Filtering - Duplicate Removal, removal 
pass: 988566 unique relations remain in total
PID7272 2017-02-27 15:41:31,585 Debug:Filtering - Duplicate Removal, removal 
pass: Exit Duplicates2Task.run(duplicates2)
PID7272 2017-02-27 15:41:31,585 Info:Filtering - Duplicate Removal, removal 
pass: Total cpu/real time for dup2: 21.82/13.5126
PID7272 2017-02-27 15:41:31,585 Info:Filtering - Singleton removal: Starting
PID7272 2017-02-27 15:41:31,590 Debug:Filtering - Singleton removal: 
purge.run(): Task state: {'input_nrels': 0}
PID7272 2017-02-27 15:41:31,647 Info:Filtering - Singleton removal: Reading 
988566 unique and 12307 free relations, total 1000873
PID7272 2017-02-27 15:41:31,961 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/purge -out 
/home/user/dlp/60_ok/p60.purged.gz -nrels 1000873 -outdel 
/home/user/dlp/60_ok/p60.relsdel.gz -keep 2 -col-min-index 14797 
-col-max-index 591911 -t 10 /home/user/dlp/60_ok/p60.dup1//0/dup1.0.0000.gz 
/home/user/dlp/60_ok/p60.dup1//1/dup1.0.0000.gz 
/home/user/dlp/60_ok/p60.freerel.gz > /home/user/dlp/60_ok/p60.purge.stdout.1 
2> /home/user/dlp/60_ok/p60.purge.stderr.1
PID7272 2017-02-27 15:41:34,183 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:34,185 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:41:34,491 Info:HTTP server: 127.0.0.1 Sending workunit 
p60_sieving_227000-228000 to client localhost
PID7272 2017-02-27 15:41:34,494 Debug:HTTP server: 127.0.0.1 "GET 
/cgi-bin/getwu?clientid=localhost HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:52,580 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:41:52,581 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:42:09,098 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:42:09,100 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:42:20,978 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:42:20,978 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:42:21,353 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:42:21,353 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:42:21,692 Debug:Command: Process with PID 17458 
finished successfully
PID7272 2017-02-27 15:42:23,142 Info:Filtering - Singleton removal: After 
purge, 25041 relations with 25039 primes remain with weight 533461 and excess 
2
PID7272 2017-02-27 15:42:24,423 Info:Filtering - Singleton removal: Have 
enough relations
PID7272 2017-02-27 15:42:24,424 Info:HTTP server: Got notification to stop 
serving Workunits
PID7272 2017-02-27 15:42:24,424 Info:Lattice Sieving: Cancelling remaining 
workunits
PID7272 2017-02-27 15:42:24,535 Command:Command: /bin/kill 7282
PID7272 2017-02-27 15:42:24,536 Debug:Command: Process with PID 21826 
finished successfully
PID7272 2017-02-27 15:42:24,536 Info:Client Launcher: Stopped client 
localhost (Host localhost, PID 7282)
PID7272 2017-02-27 15:42:26,132 Command:Command: /bin/kill 7289
PID7272 2017-02-27 15:42:26,133 Debug:Command: Process with PID 21827 
finished successfully
PID7272 2017-02-27 15:42:26,133 Info:Client Launcher: Stopped client 
localhost+4 (Host localhost, PID 7289)
PID7272 2017-02-27 15:42:26,186 Command:Command: /bin/kill 7284
PID7272 2017-02-27 15:42:26,186 Debug:Command: Process with PID 21828 
finished successfully
PID7272 2017-02-27 15:42:26,187 Info:Client Launcher: Stopped client 
localhost+2 (Host localhost, PID 7284)
PID7272 2017-02-27 15:42:26,247 Command:Command: /bin/kill 7299
PID7272 2017-02-27 15:42:26,247 Debug:Command: Process with PID 21829 
finished successfully
PID7272 2017-02-27 15:42:26,248 Info:Client Launcher: Stopped client 
localhost+5 (Host localhost, PID 7299)
PID7272 2017-02-27 15:42:26,315 Command:Command: /bin/kill 7287
PID7272 2017-02-27 15:42:26,316 Debug:Command: Process with PID 21830 
finished successfully
PID7272 2017-02-27 15:42:26,316 Info:Client Launcher: Stopped client 
localhost+3 (Host localhost, PID 7287)
PID7272 2017-02-27 15:42:26,324 Debug:Filtering - Singleton removal: Exit 
PurgeTask.run(purge)
PID7272 2017-02-27 15:42:26,324 Info:Filtering - Singleton removal: Total 
cpu/real time for purge: 56.63/49.7316
PID7272 2017-02-27 15:42:26,324 Info:Filtering - Merging: Starting
PID7272 2017-02-27 15:42:26,324 Debug:Filtering - Merging: mergedlp.run(): 
Task state: {}
PID7272 2017-02-27 15:42:36,645 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/merge-dl -mat 
/home/user/dlp/60_ok/p60.purged.gz -out /home/user/dlp/60_ok/p60.history.gz 
-maxlevel 15 -keep 2 -skip 0 -target_density 100.0 -t 10 > 
/home/user/dlp/60_ok/p60.merge-dl.stdout.1 2> 
/home/user/dlp/60_ok/p60.merge-dl.stderr.1
PID7272 2017-02-27 15:42:39,478 Debug:Command: Process with PID 21831 
finished successfully
PID7272 2017-02-27 15:42:40,041 Info:Filtering - Merging: Merged matrix has 
7321 rows and total weight 732296 (100.0 entries per row on average)
PID7272 2017-02-27 15:42:40,099 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/replay-dl -purged 
/home/user/dlp/60_ok/p60.purged.gz -ideals /home/user/dlp/60_ok/p60.ideal 
-his /home/user/dlp/60_ok/p60.history.gz -index 
/home/user/dlp/60_ok/p60.index.gz -out /home/user/dlp/60_ok/p60.sparse.bin > 
/home/user/dlp/60_ok/p60.replay.stdout.2 2> 
/home/user/dlp/60_ok/p60.replay.stderr.2
PID7272 2017-02-27 15:42:40,411 Debug:Command: Process with PID 21852 
finished successfully
PID7272 2017-02-27 15:42:41,950 Debug:HTTP server: 127.0.0.1 "POST 
/cgi-bin/upload.py HTTP/1.1" 200 -
PID7272 2017-02-27 15:42:41,952 Debug:HTTP server: 127.0.0.1 Translated path 
cgi-bin/upload.py to $CADO_DIR/lib/cado-nfs-2.2.1/scripts/cadofactor/upload.py
PID7272 2017-02-27 15:42:41,991 Warning:HTTP server: 127.0.0.1 [Errno 104] 
Connection reset by peer
PID7272 2017-02-27 15:42:42,883 Debug:Filtering - Merging: Exit 
MergeDLPTask.run(mergedlp)
PID7272 2017-02-27 15:42:42,884 Info:Filtering - Merging: Total cpu/real time 
for merge-dl: 3.9/2.83259
PID7272 2017-02-27 15:42:42,885 Info:Filtering - Merging: Total cpu/real time 
for replay-dl: 0.32/0.311457
PID7272 2017-02-27 15:42:42,885 Info:Schirokauer Maps: Starting
PID7272 2017-02-27 15:42:42,886 Debug:Schirokauer Maps: sm.run(): Task state: 
{}
PID7272 2017-02-27 15:42:43,425 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/sm -poly /home/user/dlp/60_ok/p60.poly 
-purged /home/user/dlp/60_ok/p60.purged.gz -index 
/home/user/dlp/60_ok/p60.index.gz -out /home/user/dlp/60_ok/p60.sm -ell 
157079632679489661923132169163975144209858469968755291072921 -nsm '0,2' -t 10 
> /home/user/dlp/60_ok/p60.sm.stdout.1 2> /home/user/dlp/60_ok/p60.sm.stderr.1
PID7272 2017-02-27 15:42:59,796 Debug:Command: Process with PID 21870 
finished successfully
PID7272 2017-02-27 15:43:02,172 Debug:Schirokauer Maps: Exit SMTask.run(sm)
PID7272 2017-02-27 15:43:02,172 Info:Schirokauer Maps: Total cpu/real time 
for sm: 147.35/16.3709
PID7272 2017-02-27 15:43:02,172 Info:Linear Algebra: Starting
PID7272 2017-02-27 15:43:02,173 Debug:Linear Algebra: linalgdlp.run(): Task 
state: {}
PID7272 2017-02-27 15:43:02,230 Info:Linear Algebra: Using 2 as default value 
for n to account for Schirokauer maps
PID7272 2017-02-27 15:43:02,231 Info:Linear Algebra: Using 2*n=4 as default 
value for m
PID7272 2017-02-27 15:43:02,296 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/linalg/bwc/bwc.pl :complete 'thr=10' 'm=4' 'n=2' 
'nullspace=right' 'matrix=/home/user/dlp/60_ok/p60.sparse.bin' 
'rhs=/home/user/dlp/60_ok/p60.sm' 
'prime=157079632679489661923132169163975144209858469968755291072921' 
'wdir=/home/user/dlp/60_ok/p60.bwc' 
'cpubinding=/home/user/cado/cado_build/share/cado-nfs-2.2.1/misc/cpubinding.conf'
 'cantor_threshold=2048' > /home/user/dlp/60_ok/p60.bwc.stdout.1 2> 
/home/user/dlp/60_ok/p60.bwc.stderr.1
PID7272 2017-02-27 15:48:24,510 Debug:Command: Process with PID 21897 
finished successfully
PID7272 2017-02-27 15:48:24,799 Debug:Linear Algebra: Exit 
LinAlgTask.run(linalgdlp)
PID7272 2017-02-27 15:48:24,800 Info:Linear Algebra: Total cpu/real time for 
bwc: 838.86/322.214
PID7272 2017-02-27 15:48:24,800 Info:Logarithms Reconstruction: Starting
PID7272 2017-02-27 15:48:24,800 Debug:Logarithms Reconstruction: 
reconstructlog.run(): Task state: {}
PID7272 2017-02-27 15:48:25,051 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/filter/reconstructlog-dl -ell 
157079632679489661923132169163975144209858469968755291072921 -mt 10 -log 
/home/user/dlp/60_ok/p60.bwc/K.sols0-1.0.txt -out 
/home/user/dlp/60_ok/p60.dlog -renumber /home/user/dlp/60_ok/p60.renumber.gz 
-poly /home/user/dlp/60_ok/p60.poly -purged 
/home/user/dlp/60_ok/p60.purged.gz -ideals /home/user/dlp/60_ok/p60.ideal 
-relsdel /home/user/dlp/60_ok/p60.relsdel.gz -nrels 1000873 -nsm '0,2' > 
/home/user/dlp/60_ok/p60.reconstructlog-dl.stdout.1 2> 
/home/user/dlp/60_ok/p60.reconstructlog-dl.stderr.1
PID7272 2017-02-27 16:26:26,818 Debug:Command: Process with PID 22055 
finished successfully
PID7272 2017-02-27 16:26:26,975 Debug:Logarithms Reconstruction: Exit 
ReconstructLogTask.run(reconstructlog)
PID7272 2017-02-27 16:26:26,976 Info:Logarithms Reconstruction: Total 
cpu/real time for reconstructlog-dl: 20255.9/2281.81
PID7272 2017-02-27 16:26:26,976 Info:Individual logarithm: Starting
PID7272 2017-02-27 16:26:26,976 Debug:Individual logarithm: descent.run(): 
Task state: {}
PID7272 2017-02-27 16:26:27,143 Command:Command: 
$CADO_DIR/lib/cado-nfs-2.2.1/scripts/descent.py --target 4 --prefix p60 
--datadir /home/user/dlp/60_ok --cadobindir $CADO_DIR/lib/cado-nfs-2.2.1 
--descent-hint $CADO_DIR/share/cado-nfs-2.2.1/dlp/p60.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 
500000 --lim1 600000 --ell 
157079632679489661923132169163975144209858469968755291072921 > 
/home/user/dlp/60_ok/p60.descent.stdout.1 2> 
/home/user/dlp/60_ok/p60.descent.stderr.1
PID7272 2017-02-27 17:28:01,680 Critical:Complete Factorization: Received 
KeyboardInterrupt. Terminating
PID7272 2017-02-27 17:28:03,546 Info:HTTP server: Shutting down HTTP server