runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: runsolver -s SIGUSR1 -M 1124 -C 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/easy/rand736.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/easy/rand736.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/easy/rand736.cudf.result -count(removed),-sum(solution,installedsize),-notuptodate(solution),-unsat_recommends(solution),-count(new) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB

[startup+0 s]
/proc/loadavg: 1.81 1.43 1.20 2/59 3409
/proc/meminfo: memFree=457708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=3152 CPUtime=0
/proc/3409/stat : 3409 (runsolver) R 3408 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 119864657 3227648 32 18446744073709551615 134512640 134586868 4291646944 4291644992 4151583792 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.134991 s]
/proc/loadavg: 1.81 1.43 1.20 2/59 3409
/proc/meminfo: memFree=457708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200359 s]
/proc/loadavg: 1.81 1.43 1.20 2/59 3409
/proc/meminfo: memFree=457708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300329 s]
/proc/loadavg: 1.81 1.43 1.20 2/59 3409
/proc/meminfo: memFree=457708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.70024 s]
/proc/loadavg: 1.81 1.43 1.20 2/59 3409
/proc/meminfo: memFree=457708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50084 s]
/proc/loadavg: 1.81 1.43 1.20 4/70 3421
/proc/meminfo: memFree=414708/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1056032 CPUtime=1.45
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 31388 0 0 0 128 17 0 0 20 0 10 0 119864658 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264008 28224 2348 9 0 251358 0
[pid=3412/tid=3413] ppid=3409 vsize=1056032 CPUtime=0.69
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 13244 0 0 0 59 10 0 0 20 0 10 0 119864658 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970569347744 139970467281897 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119864661 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119864662 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 119864662 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119864665 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1056032 CPUtime=0.38
/proc/3412/task/3418/stat : 3418 (java) R 3409 3409 1733 0 -1 4202560 9086 0 0 0 35 3 0 0 20 0 10 0 119864665 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970457768776 139970545667264 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1056032 CPUtime=0.37
/proc/3412/task/3419/stat : 3419 (java) R 3409 3409 1733 0 -1 4202560 7766 0 0 0 34 3 0 0 20 0 10 0 119864665 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970456715896 139970545646276 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1056032 CPUtime=0
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081376768 28224 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.45
Current children cumulated vsize (KiB) 1065200

[startup+3.10057 s]
/proc/loadavg: 1.90 1.46 1.21 4/70 3421
/proc/meminfo: memFree=316376/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1056512 CPUtime=3.03
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 78852 0 0 0 266 37 0 0 20 0 10 0 119864658 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264128 74009 2369 9 0 251478 0
[pid=3412/tid=3413] ppid=3409 vsize=1056512 CPUtime=1.5
/proc/3412/task/3413/stat : 3413 (java) S 3409 3409 1733 0 -1 4202560 47409 0 0 0 126 24 0 0 20 0 10 0 119864658 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970569355872 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1056512 CPUtime=0.17
/proc/3412/task/3414/stat : 3414 (java) R 3409 3409 1733 0 -1 4202560 10845 0 0 0 14 3 0 0 20 0 10 0 119864661 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970461993488 139970549000769 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119864662 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 119864662 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119864665 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1056512 CPUtime=0.64
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10764 0 0 0 59 5 0 0 20 0 10 0 119864665 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1056512 CPUtime=0.69
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8542 0 0 0 66 3 0 0 20 0 10 0 119864665 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081868288 74009 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.03
Current children cumulated vsize (KiB) 1065680

[startup+6.30077 s]
/proc/loadavg: 1.90 1.46 1.21 4/70 3421
/proc/meminfo: memFree=11460/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1056512 CPUtime=6.09
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 121714 0 0 0 558 51 0 0 20 0 10 0 119864658 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264128 116511 2374 9 0 251478 0
[pid=3412/tid=3413] ppid=3409 vsize=1056512 CPUtime=3.11
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47704 0 0 0 285 26 0 0 20 0 10 0 119864658 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970569355576 139970466817518 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1056512 CPUtime=1.02
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 53196 0 0 0 88 14 0 0 20 0 10 0 119864661 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119864662 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 119864662 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119864665 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1056512 CPUtime=0.91
/proc/3412/task/3418/stat : 3418 (java) R 3409 3409 1733 0 -1 4202560 10812 0 0 0 86 5 0 0 20 0 10 0 119864665 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970457783056 139970544596206 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1056512 CPUtime=1.01
/proc/3412/task/3419/stat : 3419 (java) R 3409 3409 1733 0 -1 4202560 8710 0 0 0 98 3 0 0 20 0 10 0 119864665 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970456730384 139970544729827 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1056512 CPUtime=0
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119864666 1081868288 116511 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.09
Current children cumulated vsize (KiB) 1065680

[startup+12.7008 s]
/proc/loadavg: 1.91 1.48 1.22 2/71 3422
/proc/meminfo: memFree=9840/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=12.41
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 122121 0 0 0 1186 55 0 0 20 0 11 0 119864658 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 116918 2381 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=6.68
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47792 0 0 0 640 28 0 0 20 0 11 0 119864658 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970569355368 139970468178221 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=1.04
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 53197 0 0 0 88 16 0 0 20 0 11 0 119864661 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.23
/proc/3412/task/3418/stat : 3418 (java) R 3409 3409 1733 0 -1 4202560 10942 0 0 0 217 6 0 0 20 0 11 0 119864665 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970457783056 139970545672549 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.41
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 238 3 0 0 20 0 11 0 119864665 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 116918 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.41
Current children cumulated vsize (KiB) 1066708

[startup+25.5009 s]
/proc/loadavg: 1.77 1.46 1.21 2/71 3422
/proc/meminfo: memFree=9840/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=25.03
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 122127 0 0 0 2448 55 0 0 20 0 11 0 119864658 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 116924 2381 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=19.22
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47794 0 0 0 1894 28 0 0 20 0 11 0 119864658 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970569346976 139970468204898 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=1.06
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 53197 0 0 0 89 17 0 0 20 0 11 0 119864661 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.27
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10946 0 0 0 221 6 0 0 20 0 11 0 119864665 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.44
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 241 3 0 0 20 0 11 0 119864665 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.01
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 119864666 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 116924 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.03
Current children cumulated vsize (KiB) 1066708

[startup+51.1006 s]
/proc/loadavg: 1.51 1.42 1.21 2/71 3422
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=50.21
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132456 0 0 0 4963 58 0 0 20 0 11 0 119864658 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127249 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=41.26
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 4098 28 0 0 20 0 11 0 119864658 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468178221 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=4.08
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63521 0 0 0 388 20 0 0 20 0 11 0 119864661 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.32
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10947 0 0 0 226 6 0 0 20 0 11 0 119864665 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.02
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 119864666 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127249 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.21
Current children cumulated vsize (KiB) 1066708

[startup+102.301 s]
/proc/loadavg: 1.20 1.35 1.19 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=100.7
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132464 0 0 0 10010 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=88.29
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 8800 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468205100 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=7.48
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63522 0 0 0 728 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.05
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 9 0 0 0 5 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.7
Current children cumulated vsize (KiB) 1066708

Solver just ended. Dumping a history of the last processes samples

[startup+102.409 s]
/proc/loadavg: 1.20 1.35 1.19 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=100.81
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132464 0 0 0 10021 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=88.39
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 8810 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468178890 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=7.48
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63522 0 0 0 728 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.05
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 9 0 0 0 5 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.81
Current children cumulated vsize (KiB) 1066708

[startup+128.001 s]
/proc/loadavg: 1.13 1.32 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=126.05
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132465 0 0 0 12545 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=111.94
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 11165 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468178631 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=9.16
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 896 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.07
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 9 0 0 0 7 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 126.05
Current children cumulated vsize (KiB) 1066708

[startup+134.401 s]
/proc/loadavg: 1.12 1.31 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=132.36
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132465 0 0 0 13176 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=118.22
/proc/3412/task/3413/stat : 3413 (java) S 3409 3409 1733 0 -1 4202560 47795 0 0 0 11793 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355120 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=9.18
/proc/3412/task/3414/stat : 3414 (java) R 3409 3409 1733 0 -1 4202560 63523 0 0 0 898 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461993520 139970547184037 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.07
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 9 0 0 0 7 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 132.36
Current children cumulated vsize (KiB) 1066708

[startup+137.605 s]
/proc/loadavg: 1.11 1.31 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=135.52
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132468 0 0 0 13492 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=120.56
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 12027 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468179129 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.07
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 7 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 135.52
Current children cumulated vsize (KiB) 1066708

[startup+140.801 s]
/proc/loadavg: 1.11 1.31 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=138.69
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132468 0 0 0 13809 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=123.72
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 12343 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970467370411 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.07
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 7 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 138.69
Current children cumulated vsize (KiB) 1066708

[startup+142.401 s]
/proc/loadavg: 1.10 1.30 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1057540 CPUtime=140.26
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132468 0 0 0 13966 60 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264385 127250 2393 9 0 251735 0
[pid=3412/tid=3413] ppid=3409 vsize=1057540 CPUtime=125.29
/proc/3412/task/3413/stat : 3413 (java) R 3409 3409 1733 0 -1 4202560 47795 0 0 0 12500 29 0 0 20 0 11 0 119864658 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970569355320 139970468178005 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1057540 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1057540 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1057540 CPUtime=2.5
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 247 3 0 0 20 0 11 0 119864665 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1057540 CPUtime=0.08
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 119864666 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3422] ppid=3409 vsize=1057540 CPUtime=0
/proc/3412/task/3422/stat : 3422 (java) S 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119865606 1082920960 127250 18446744073709551615 4194304 4228012 140736356251728 139970453419872 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 140.26
Current children cumulated vsize (KiB) 1066708

[startup+142.801 s]
/proc/loadavg: 1.10 1.30 1.18 2/71 3422
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1058568 CPUtime=140.36
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132483 0 0 0 13976 60 0 0 20 0 11 0 119864658 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264642 127262 2393 9 0 251992 0
[pid=3412/tid=3413] ppid=3409 vsize=1058568 CPUtime=125.37
/proc/3412/task/3413/stat : 3413 (java) S 3409 3409 1733 0 -1 4202560 47804 0 0 0 12508 29 0 0 20 0 11 0 119864658 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970569356208 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1058568 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1058568 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1058568 CPUtime=2.51
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 248 3 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1058568 CPUtime=0.08
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 119864666 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 140.36
Current children cumulated vsize (KiB) 1067736

[startup+143.002 s]
/proc/loadavg: 1.10 1.30 1.18 2/71 3423
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1058568 CPUtime=140.36
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132483 0 0 0 13976 60 0 0 20 0 11 0 119864658 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264642 127262 2393 9 0 251992 0
[pid=3412/tid=3413] ppid=3409 vsize=1058568 CPUtime=125.37
/proc/3412/task/3413/stat : 3413 (java) S 3409 3409 1733 0 -1 4202560 47804 0 0 0 12508 29 0 0 20 0 11 0 119864658 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970569356208 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1058568 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1058568 CPUtime=2.34
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10948 0 0 0 228 6 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1058568 CPUtime=2.51
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 248 3 0 0 20 0 11 0 119864665 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1058568 CPUtime=0.08
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 119864666 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3423] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3423/stat : 3423 (java) R 3409 3409 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119878906 1083973632 127262 18446744073709551615 4194304 4228012 140736356251728 139970452358336 139970466831860 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 140.36
Current children cumulated vsize (KiB) 1067736

[startup+143.109 s]
/proc/loadavg: 1.10 1.30 1.18 2/71 3423
/proc/meminfo: memFree=10008/1022884 swapFree=0/0
[pid=3409] ppid=3408 vsize=9168 CPUtime=0
/proc/3409/stat : 3409 (p2cudf-full-1.1) S 3408 3409 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119864657 9388032 331 18446744073709551615 4194304 5129932 140735848789600 140735848787744 140517681288286 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3409/statm: 2292 331 280 229 0 52 0
[pid=3412] ppid=3409 vsize=1058568 CPUtime=140.46
/proc/3412/stat : 3412 (java) S 3409 3409 1733 0 -1 4202496 132487 0 0 0 13983 63 0 0 20 0 11 0 119864658 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 140736356242752 139970562821749 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3412/statm: 264642 127266 2393 9 0 251992 0
[pid=3412/tid=3413] ppid=3409 vsize=1058568 CPUtime=125.37
/proc/3412/task/3413/stat : 3413 (java) S 3409 3409 1733 0 -1 4202560 47804 0 0 0 12508 29 0 0 20 0 11 0 119864658 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970569356208 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0
[pid=3412/tid=3414] ppid=3409 vsize=1058568 CPUtime=10.01
/proc/3412/task/3414/stat : 3414 (java) S 3409 3409 1733 0 -1 4202560 63523 0 0 0 981 20 0 0 20 0 11 0 119864661 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970461994112 139970562836043 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3415] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3415/stat : 3415 (java) S 3409 3409 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970460940304 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3416] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3416/stat : 3416 (java) S 3409 3409 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 119864662 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970459887520 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3417] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3417/stat : 3417 (java) S 3409 3409 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 119864665 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970458835952 139970562843680 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3418] ppid=3409 vsize=1058568 CPUtime=2.35
/proc/3412/task/3418/stat : 3418 (java) S 3409 3409 1733 0 -1 4202560 10949 0 0 0 229 6 0 0 20 0 11 0 119864665 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970457783312 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3419] ppid=3409 vsize=1058568 CPUtime=2.52
/proc/3412/task/3419/stat : 3419 (java) S 3409 3409 1733 0 -1 4202560 8892 0 0 0 249 3 0 0 20 0 11 0 119864665 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970456730640 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3420] ppid=3409 vsize=1058568 CPUtime=0
/proc/3412/task/3420/stat : 3420 (java) S 3409 3409 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119864666 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970455678160 139970562835156 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3421] ppid=3409 vsize=1058568 CPUtime=0.08
/proc/3412/task/3421/stat : 3421 (java) S 3409 3409 1733 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 119864666 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970454625664 139970562836043 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3412/tid=3423] ppid=3409 vsize=1058568 CPUtime=0.09
/proc/3412/task/3423/stat : 3423 (java) R 3409 3409 1733 0 -1 4202560 9 0 0 0 6 3 0 0 20 0 11 0 119878906 1083973632 127266 18446744073709551615 4194304 4228012 140736356251728 139970452358480 139970466867869 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 140.46
Current children cumulated vsize (KiB) 1067736

Child status: 0
Real time (s): 143.172
CPU time (s): 140.537
CPU user time (s): 139.873
CPU system time (s): 0.664041
CPU usage (%): 98.1593
Max. virtual memory (cumulated for all children) (KiB): 1071016

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 139.873
system time used= 0.664041
maximum resident set size= 509100
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 133260
page faults= 0
swaps= 0
block input operations= 42376
block output operations= 368
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 3924
involuntary context switches= 21045

runsolver used 0.524032 second user time and 1.01606 second system time

The end