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/difficult/rand21.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/difficult/rand21.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/upgrade/difficult/rand21.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.09 1.16 1.15 2/59 3485
/proc/meminfo: memFree=510792/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=3152 CPUtime=0
/proc/3485/stat : 3485 (runsolver) R 3484 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 119916309 3227648 32 18446744073709551615 134512640 134586868 4294245744 4294243792 4151936048 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.110942 s]
/proc/loadavg: 1.09 1.16 1.15 2/59 3485
/proc/meminfo: memFree=510792/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.202297 s]
/proc/loadavg: 1.09 1.16 1.15 2/59 3485
/proc/meminfo: memFree=510792/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300315 s]
/proc/loadavg: 1.09 1.16 1.15 2/59 3485
/proc/meminfo: memFree=510792/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700253 s]
/proc/loadavg: 1.09 1.16 1.15 2/59 3485
/proc/meminfo: memFree=510792/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50076 s]
/proc/loadavg: 1.24 1.19 1.16 4/70 3497
/proc/meminfo: memFree=468164/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1064756 CPUtime=1.45
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 31672 0 0 0 130 15 0 0 20 0 10 0 119916310 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266189 28563 2346 9 0 253539 0
[pid=3488/tid=3489] ppid=3485 vsize=1064756 CPUtime=0.68
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 13556 0 0 0 60 8 0 0 20 0 10 0 119916311 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842902508192 139842800369456 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119916314 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119916315 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 10 0 119916315 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1064756 CPUtime=0.36
/proc/3488/task/3494/stat : 3494 (java) R 3485 3485 1733 0 -1 4202560 8951 0 0 0 33 3 0 0 20 0 10 0 119916318 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842790929224 139842882074288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1064756 CPUtime=0.38
/proc/3488/task/3495/stat : 3495 (java) R 3485 3485 1733 0 -1 4202560 7873 0 0 0 36 2 0 0 20 0 10 0 119916318 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842789876120 139842882714813 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1064756 CPUtime=0
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090310144 28563 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 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) 1073924

[startup+3.10079 s]
/proc/loadavg: 1.24 1.19 1.16 4/70 3497
/proc/meminfo: memFree=368592/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1064764 CPUtime=3.03
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 76533 0 0 0 270 33 0 0 20 0 10 0 119916310 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266191 71111 2366 9 0 253541 0
[pid=3488/tid=3489] ppid=3485 vsize=1064764 CPUtime=1.49
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47399 0 0 0 128 21 0 0 20 0 10 0 119916311 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842902508224 139842800433869 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1064764 CPUtime=0.17
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 7975 0 0 0 15 2 0 0 20 0 10 0 119916314 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1064764 CPUtime=0.64
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10391 0 0 0 59 5 0 0 20 0 10 0 119916318 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1064764 CPUtime=0.71
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 9475 0 0 0 67 4 0 0 20 0 10 0 119916318 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 71111 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 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) 1073932

[startup+6.30082 s]
/proc/loadavg: 1.24 1.19 1.16 2/70 3497
/proc/meminfo: memFree=63552/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1064764 CPUtime=6.08
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 122514 0 0 0 561 47 0 0 20 0 10 0 119916310 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266191 116979 2368 9 0 253541 0
[pid=3488/tid=3489] ppid=3485 vsize=1064764 CPUtime=3.35
/proc/3488/task/3489/stat : 3489 (java) S 3485 3485 1733 0 -1 4202560 47402 0 0 0 313 22 0 0 20 0 10 0 119916311 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842902516240 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1064764 CPUtime=1.28
/proc/3488/task/3490/stat : 3490 (java) R 3485 3485 1733 0 -1 4202560 53949 0 0 0 114 14 0 0 20 0 10 0 119916314 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842795153936 139842880292985 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1064764 CPUtime=0.67
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10391 0 0 0 62 5 0 0 20 0 10 0 119916318 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1064764 CPUtime=0.75
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 9478 0 0 0 71 4 0 0 20 0 10 0 119916318 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 116979 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.08
Current children cumulated vsize (KiB) 1073932

[startup+12.7008 s]
/proc/loadavg: 1.37 1.21 1.17 3/70 3497
/proc/meminfo: memFree=9496/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1064764 CPUtime=12.14
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 156697 0 0 0 1156 58 0 0 20 0 10 0 119916310 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266191 148989 2376 9 0 253541 0
[pid=3488/tid=3489] ppid=3485 vsize=1064764 CPUtime=6.05
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47708 0 0 0 581 24 0 0 20 0 10 0 119916311 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842902516728 139842800784990 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1064764 CPUtime=2.07
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 83697 0 0 0 184 23 0 0 20 0 10 0 119916314 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 10 0 119916315 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1064764 CPUtime=1.85
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10573 0 0 0 180 5 0 0 20 0 10 0 119916318 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1064764 CPUtime=2.14
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13425 0 0 0 208 6 0 0 20 0 10 0 119916318 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1064764 CPUtime=0
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119916318 1090318336 148989 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.14
Current children cumulated vsize (KiB) 1073932

[startup+25.5008 s]
/proc/loadavg: 1.47 1.24 1.18 2/71 3498
/proc/meminfo: memFree=9256/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=24.76
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 156830 0 0 0 2416 60 0 0 20 0 11 0 119916310 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 149098 2381 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=17.42
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47789 0 0 0 1718 24 0 0 20 0 11 0 119916311 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842902516040 139842801117596 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=2.1
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 83698 0 0 0 186 24 0 0 20 0 11 0 119916314 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.51
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10602 0 0 0 246 5 0 0 20 0 11 0 119916318 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.7
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13441 0 0 0 264 6 0 0 20 0 11 0 119916318 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.01
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 1 0 0 0 0 1 0 0 20 0 11 0 119916318 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 149098 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.76
Current children cumulated vsize (KiB) 1074960

[startup+51.1008 s]
/proc/loadavg: 1.28 1.21 1.17 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=50
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 167968 0 0 0 4937 63 0 0 20 0 11 0 119916310 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 160232 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=41.19
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47790 0 0 0 4094 25 0 0 20 0 11 0 119916311 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842902513832 139842801380075 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=3.46
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 94821 0 0 0 320 26 0 0 20 0 11 0 119916314 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.55
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10605 0 0 0 250 5 0 0 20 0 11 0 119916318 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.75
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13449 0 0 0 269 6 0 0 20 0 11 0 119916318 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.02
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 4 0 0 0 0 2 0 0 20 0 11 0 119916318 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 160232 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50
Current children cumulated vsize (KiB) 1074960

[startup+102.301 s]
/proc/loadavg: 1.12 1.18 1.16 2/71 3498
/proc/meminfo: memFree=10272/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=100.52
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 169751 0 0 0 9988 64 0 0 20 0 11 0 119916310 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162011 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=87.69
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 8744 25 0 0 20 0 11 0 119916311 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801336464 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=7.39
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 96599 0 0 0 713 26 0 0 20 0 11 0 119916314 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.57
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10605 0 0 0 252 5 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.77
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13450 0 0 0 271 6 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.05
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 7 0 0 0 0 5 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.52
Current children cumulated vsize (KiB) 1074960

[startup+162.301 s]
/proc/loadavg: 1.04 1.14 1.15 2/71 3498
/proc/meminfo: memFree=10148/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=159.71
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 169758 0 0 0 15907 64 0 0 20 0 11 0 119916310 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162011 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=144.39
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 14414 25 0 0 20 0 11 0 119916311 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801117784 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=9.85
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 96600 0 0 0 959 26 0 0 20 0 11 0 119916314 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.57
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10605 0 0 0 252 5 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.77
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13450 0 0 0 271 6 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.08
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 13 0 0 0 0 8 0 0 20 0 11 0 119916318 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162011 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 159.71
Current children cumulated vsize (KiB) 1074960

[startup+222.305 s]
/proc/loadavg: 1.01 1.12 1.14 2/71 3498
/proc/meminfo: memFree=10024/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=218.93
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170284 0 0 0 21828 65 0 0 20 0 11 0 119916310 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162529 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=201.08
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 20083 25 0 0 20 0 11 0 119916311 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801380688 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=12.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97120 0 0 0 1208 27 0 0 20 0 11 0 119916314 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.58
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10605 0 0 0 253 5 0 0 20 0 11 0 119916318 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.77
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13450 0 0 0 271 6 0 0 20 0 11 0 119916318 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.11
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 19 0 0 0 0 11 0 0 20 0 11 0 119916318 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162529 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 218.93
Current children cumulated vsize (KiB) 1074960

[startup+282.301 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9900/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=278.1
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170296 0 0 0 27745 65 0 0 20 0 11 0 119916310 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162534 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=259.18
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 25893 25 0 0 20 0 11 0 119916311 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842800846775 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.59
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10608 0 0 0 254 5 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.79
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13452 0 0 0 273 6 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.15
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.1
Current children cumulated vsize (KiB) 1074960

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=283.72
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170296 0 0 0 28307 65 0 0 20 0 11 0 119916310 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162534 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=264.8
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 26455 25 0 0 20 0 11 0 119916311 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801380743 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.59
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10608 0 0 0 254 5 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.79
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13452 0 0 0 273 6 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.15
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 283.72
Current children cumulated vsize (KiB) 1074960

[startup+294.401 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=290.03
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170296 0 0 0 28938 65 0 0 20 0 11 0 119916310 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162534 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=271.11
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 27086 25 0 0 20 0 11 0 119916311 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801380333 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.59
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10608 0 0 0 254 5 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.79
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13452 0 0 0 273 6 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.15
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 15 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.03
Current children cumulated vsize (KiB) 1074960

[startup+297.601 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=293.19
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170296 0 0 0 29254 65 0 0 20 0 11 0 119916310 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162534 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=274.26
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 27401 25 0 0 20 0 11 0 119916311 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842800846890 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.59
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10608 0 0 0 254 5 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.79
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13452 0 0 0 273 6 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.16
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 16 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.19
Current children cumulated vsize (KiB) 1074960

[startup+298.401 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1065792 CPUtime=293.97
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170296 0 0 0 29332 65 0 0 20 0 11 0 119916310 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266448 162534 2393 9 0 253798 0
[pid=3488/tid=3489] ppid=3485 vsize=1065792 CPUtime=275.05
/proc/3488/task/3489/stat : 3489 (java) R 3485 3485 1733 0 -1 4202560 47791 0 0 0 27480 25 0 0 20 0 11 0 119916311 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842902515784 139842801380688 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1065792 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1065792 CPUtime=2.59
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10608 0 0 0 254 5 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1065792 CPUtime=2.79
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13452 0 0 0 273 6 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1065792 CPUtime=0.16
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 16 0 0 20 0 11 0 119916318 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3498] ppid=3485 vsize=1065792 CPUtime=0
/proc/3488/task/3498/stat : 3498 (java) S 3485 3485 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 119917649 1091371008 162534 18446744073709551615 4194304 4228012 140733508459216 139842786580320 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.97
Current children cumulated vsize (KiB) 1074960

[startup+298.801 s]
/proc/loadavg: 1.00 1.09 1.13 2/71 3498
/proc/meminfo: memFree=9776/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=1066820 CPUtime=294.07
/proc/3488/stat : 3488 (java) S 3485 3485 1733 0 -1 4202496 170314 0 0 0 29342 65 0 0 20 0 11 0 119916310 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 140733508450240 139842895982197 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 266705 162549 2393 9 0 254055 0
[pid=3488/tid=3489] ppid=3485 vsize=1066820 CPUtime=275.08
/proc/3488/task/3489/stat : 3489 (java) S 3485 3485 1733 0 -1 4202560 47800 0 0 0 27483 25 0 0 20 0 11 0 119916311 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842902516656 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 15 0 0
[pid=3488/tid=3490] ppid=3485 vsize=1066820 CPUtime=13.35
/proc/3488/task/3490/stat : 3490 (java) S 3485 3485 1733 0 -1 4202560 97121 0 0 0 1308 27 0 0 20 0 11 0 119916314 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842795154560 139842895996491 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3491] ppid=3485 vsize=1066820 CPUtime=0
/proc/3488/task/3491/stat : 3491 (java) S 3485 3485 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119916315 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842794100752 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3492] ppid=3485 vsize=1066820 CPUtime=0
/proc/3488/task/3492/stat : 3492 (java) S 3485 3485 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 119916315 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842793047968 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3493] ppid=3485 vsize=1066820 CPUtime=0
/proc/3488/task/3493/stat : 3493 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842791996400 139842896004128 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3494] ppid=3485 vsize=1066820 CPUtime=2.63
/proc/3488/task/3494/stat : 3494 (java) S 3485 3485 1733 0 -1 4202560 10609 0 0 0 258 5 0 0 20 0 11 0 119916318 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842790943760 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3495] ppid=3485 vsize=1066820 CPUtime=2.82
/proc/3488/task/3495/stat : 3495 (java) S 3485 3485 1733 0 -1 4202560 13454 0 0 0 276 6 0 0 20 0 11 0 119916318 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842789891088 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3496] ppid=3485 vsize=1066820 CPUtime=0
/proc/3488/task/3496/stat : 3496 (java) S 3485 3485 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119916318 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842788838608 139842895995604 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=3488/tid=3497] ppid=3485 vsize=1066820 CPUtime=0.16
/proc/3488/task/3497/stat : 3497 (java) S 3485 3485 1733 0 -1 4202560 25 0 0 0 0 16 0 0 20 0 11 0 119916318 1092423680 162549 18446744073709551615 4194304 4228012 140733508459216 139842787786112 139842895996491 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 294.07
Current children cumulated vsize (KiB) 1075988

[startup+299.201 s]
/proc/loadavg: 1.00 1.09 1.13 2/62 3499
/proc/meminfo: memFree=349112/1022884 swapFree=0/0
[pid=3485] ppid=3484 vsize=9168 CPUtime=0
/proc/3485/stat : 3485 (p2cudf-full-1.1) S 3484 3485 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 119916309 9388032 331 18446744073709551615 4194304 5129932 140734942845040 140734942843184 140178805118046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/3485/statm: 2292 331 280 229 0 52 0
[pid=3488] ppid=3485 vsize=0 CPUtime=294.26
/proc/3488/stat : 3488 (java) Z 3485 3485 1733 0 -1 4203532 170327 0 2 0 29353 73 0 0 20 0 2 0 119916310 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/3488/statm: 0 0 0 0 0 0 0
[pid=3488/tid=3499] ppid=3485 vsize=0 CPUtime=0.16
/proc/3488/task/3499/stat : 3499 (java) R 3485 3485 1733 0 -1 4203588 9 0 0 0 7 9 0 0 20 0 2 0 119946158 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 294.26
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 299.237
CPU time (s): 294.306
CPU user time (s): 293.538
CPU system time (s): 0.768048
CPU usage (%): 98.3521
Max. virtual memory (cumulated for all children) (KiB): 1079200

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 293.538
system time used= 0.768048
maximum resident set size= 650252
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 171089
page faults= 2
swaps= 0
block input operations= 68528
block output operations= 552
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7502
involuntary context switches= 42083

runsolver used 0.988061 second user time and 2.30014 second system time

The end