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/slowlink/upgrade/difficult/rand411.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/difficult/rand411.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/difficult/rand411.cudf.result -sum(changed,installedsize),-count(removed),-notuptodate(solution),-count(changed) 

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.00 1.02 1.04 2/60 29097
/proc/meminfo: memFree=634896/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=3152 CPUtime=0
/proc/29097/stat : 29097 (runsolver) R 29096 32685 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 42776775 3227648 32 18446744073709551615 134512640 134586868 4286777536 4286775584 4151989296 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/29097/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.173147 s]
/proc/loadavg: 1.00 1.02 1.04 2/60 29097
/proc/meminfo: memFree=634896/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.205258 s]
/proc/loadavg: 1.00 1.02 1.04 2/60 29097
/proc/meminfo: memFree=634896/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300263 s]
/proc/loadavg: 1.00 1.02 1.04 2/60 29097
/proc/meminfo: memFree=634896/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700217 s]
/proc/loadavg: 1.00 1.02 1.04 2/60 29097
/proc/meminfo: memFree=634896/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50072 s]
/proc/loadavg: 1.00 1.02 1.04 4/71 29109
/proc/meminfo: memFree=591772/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1052400 CPUtime=1.31
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 31179 0 15 0 120 11 0 0 20 0 10 0 42776778 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263100 28288 2348 9 0 250450 0
[pid=29100/tid=29101] ppid=29097 vsize=1052400 CPUtime=0.59
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 13652 0 3 0 53 6 0 0 20 0 10 0 42776784 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778890400416 139778787923086 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 42776794 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 42776795 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 42776795 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 42776797 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1052400 CPUtime=0.33
/proc/29100/task/29106/stat : 29106 (java) R 29097 29097 32685 0 -1 4202560 8481 0 0 0 30 3 0 0 20 0 10 0 42776797 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778778821336 139778870034431 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1052400 CPUtime=0.36
/proc/29100/task/29107/stat : 29107 (java) R 29097 29097 32685 0 -1 4202560 7766 0 0 0 35 1 0 0 20 0 10 0 42776797 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778777768520 139778869545142 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1052400 CPUtime=0
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1077657600 28288 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.31
Current children cumulated vsize (KiB) 1061568

[startup+3.10074 s]
/proc/loadavg: 1.00 1.02 1.04 3/71 29109
/proc/meminfo: memFree=484636/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1055120 CPUtime=2.89
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 76001 0 15 0 264 25 0 0 20 0 10 0 42776778 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263780 71661 2368 9 0 251130 0
[pid=29100/tid=29101] ppid=29097 vsize=1055120 CPUtime=1.41
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47413 0 3 0 124 17 0 0 20 0 10 0 42776784 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778890400448 139778788315160 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1055120 CPUtime=0.15
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 7950 0 0 0 13 2 0 0 20 0 10 0 42776794 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1055120 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 42776795 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1055120 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 42776795 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1055120 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 42776797 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1055120 CPUtime=0.63
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 10955 0 0 0 60 3 0 0 20 0 10 0 42776797 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1055120 CPUtime=0.66
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8403 0 0 0 65 1 0 0 20 0 10 0 42776797 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1055120 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1055120 CPUtime=0
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1080442880 71661 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.89
Current children cumulated vsize (KiB) 1064288

[startup+6.30074 s]
/proc/loadavg: 1.16 1.05 1.05 2/71 29109
/proc/meminfo: memFree=185672/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1052268 CPUtime=6.04
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 121614 0 15 0 566 38 0 0 20 0 10 0 42776778 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263067 117027 2369 9 0 250417 0
[pid=29100/tid=29101] ppid=29097 vsize=1052268 CPUtime=3.36
/proc/29100/task/29101/stat : 29101 (java) S 29097 29097 32685 0 -1 4202560 47416 0 3 0 318 18 0 0 20 0 10 0 42776784 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778890408496 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 12 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1052268 CPUtime=1.27
/proc/29100/task/29102/stat : 29102 (java) R 29097 29097 32685 0 -1 4202560 53553 0 0 0 113 14 0 0 20 0 10 0 42776794 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778783046160 139778868185209 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1052268 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 42776795 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1052268 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 42776795 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1052268 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 42776797 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1052268 CPUtime=0.67
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 10962 0 0 0 64 3 0 0 20 0 10 0 42776797 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1052268 CPUtime=0.69
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8403 0 0 0 68 1 0 0 20 0 10 0 42776797 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1052268 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1052268 CPUtime=0
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 42776797 1077522432 117027 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.04
Current children cumulated vsize (KiB) 1061436

[startup+12.7008 s]
/proc/loadavg: 1.31 1.08 1.06 3/72 29110
/proc/meminfo: memFree=14420/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=12.36
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 152764 0 15 0 1187 49 0 0 20 0 11 0 42776778 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 148177 2381 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=6.29
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47800 0 3 0 608 21 0 0 20 0 11 0 42776784 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778890408008 139778789093848 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=2.09
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 84038 0 0 0 186 23 0 0 20 0 11 0 42776794 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=1.88
/proc/29100/task/29106/stat : 29106 (java) R 29097 29097 32685 0 -1 4202560 11137 0 0 0 185 3 0 0 20 0 11 0 42776797 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778778835728 139778869541431 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.07
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8503 0 0 0 206 1 0 0 20 0 11 0 42776797 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 148177 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.36
Current children cumulated vsize (KiB) 1062464

[startup+25.5006 s]
/proc/loadavg: 1.39 1.11 1.07 2/72 29110
/proc/meminfo: memFree=10068/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=24.99
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 160175 0 15 0 2448 51 0 0 20 0 11 0 42776778 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 155588 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=17.07
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 1686 21 0 0 20 0 11 0 42776784 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778890407896 139778788570907 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=3.72
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 91423 0 0 0 347 25 0 0 20 0 11 0 42776794 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=1.97
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11149 0 0 0 194 3 0 0 20 0 11 0 42776797 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.17
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8514 0 0 0 216 1 0 0 20 0 11 0 42776797 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.01
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 42776797 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 155588 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.99
Current children cumulated vsize (KiB) 1062464

[startup+51.1007 s]
/proc/loadavg: 1.25 1.10 1.06 2/72 29110
/proc/meminfo: memFree=10200/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=50.37
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 161029 0 15 0 4986 51 0 0 20 0 11 0 42776778 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 156438 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=39.73
/proc/29100/task/29101/stat : 29101 (java) S 29097 29097 32685 0 -1 4202560 47803 0 3 0 3952 21 0 0 20 0 11 0 42776784 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778890407696 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=6.39
/proc/29100/task/29102/stat : 29102 (java) R 29097 29097 32685 0 -1 4202560 92272 0 0 0 614 25 0 0 20 0 11 0 42776794 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778783045744 139778866119656 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 197 3 0 0 20 0 11 0 42776797 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.2
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 219 1 0 0 20 0 11 0 42776797 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.02
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 42776797 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 156438 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.37
Current children cumulated vsize (KiB) 1062464

[startup+102.301 s]
/proc/loadavg: 1.11 1.08 1.06 2/72 29110
/proc/meminfo: memFree=9084/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=101.14
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 162322 0 15 0 10063 51 0 0 20 0 11 0 42776778 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 157724 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=85.76
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 8555 21 0 0 20 0 11 0 42776784 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778890407896 139778789121123 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=11.1
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 93559 0 0 0 1085 25 0 0 20 0 11 0 42776794 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 197 3 0 0 20 0 11 0 42776797 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.2
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 219 1 0 0 20 0 11 0 42776797 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.04
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 4 0 0 0 20 0 11 0 42776797 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 157724 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 101.14
Current children cumulated vsize (KiB) 1062464

[startup+162.301 s]
/proc/loadavg: 1.04 1.06 1.05 2/72 29110
/proc/meminfo: memFree=10252/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=160.59
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 168203 0 15 0 16005 54 0 0 20 0 11 0 42776778 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 163600 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=140.76
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 14055 21 0 0 20 0 11 0 42776784 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778890407896 139778788792442 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=15.49
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 99437 0 0 0 1522 27 0 0 20 0 11 0 42776794 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.02
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 199 3 0 0 20 0 11 0 42776797 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.2
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 219 1 0 0 20 0 11 0 42776797 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.08
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 12 0 0 0 8 0 0 0 20 0 11 0 42776797 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 163600 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.59
Current children cumulated vsize (KiB) 1062464

[startup+222.304 s]
/proc/loadavg: 1.01 1.05 1.05 2/72 29110
/proc/meminfo: memFree=10140/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=220.07
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 169966 0 15 0 21953 54 0 0 20 0 11 0 42776778 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 165355 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=198.71
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 19849 22 0 0 20 0 11 0 42776784 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778890407896 139778788792444 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=16.99
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 101194 0 0 0 1671 28 0 0 20 0 11 0 42776794 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.1
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 18 0 0 0 10 0 0 0 20 0 11 0 42776797 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 165355 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 220.07
Current children cumulated vsize (KiB) 1062464

[startup+282.301 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=279.57
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171433 0 15 0 27901 56 0 0 20 0 11 0 42776778 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 166814 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=255.93
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 25571 22 0 0 20 0 11 0 42776784 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778890407896 139778788871844 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.13
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 279.57
Current children cumulated vsize (KiB) 1062464

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

[startup+288.001 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=285.22
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171433 0 15 0 28466 56 0 0 20 0 11 0 42776778 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 166814 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=261.58
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 26136 22 0 0 20 0 11 0 42776784 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778890407880 139778788408768 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.14
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 285.22
Current children cumulated vsize (KiB) 1062464

[startup+291.201 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=288.39
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171433 0 15 0 28783 56 0 0 20 0 11 0 42776778 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 166814 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=264.76
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 26454 22 0 0 20 0 11 0 42776784 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778890407880 139778789158582 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.14
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 288.39
Current children cumulated vsize (KiB) 1062464

[startup+294.401 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=291.57
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171433 0 15 0 29101 56 0 0 20 0 11 0 42776778 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 166814 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=267.93
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 26771 22 0 0 20 0 11 0 42776784 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778890407880 139778788871844 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.14
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.57
Current children cumulated vsize (KiB) 1062464

[startup+296.004 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1053296 CPUtime=293.16
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171433 0 15 0 29260 56 0 0 20 0 11 0 42776778 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263324 166814 2393 9 0 250674 0
[pid=29100/tid=29101] ppid=29097 vsize=1053296 CPUtime=269.51
/proc/29100/task/29101/stat : 29101 (java) R 29097 29097 32685 0 -1 4202560 47803 0 3 0 26929 22 0 0 20 0 11 0 42776784 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778890407880 139778789121608 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1053296 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1053296 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1053296 CPUtime=2.21
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 220 1 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1053296 CPUtime=0.14
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 42776797 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29110] ppid=29097 vsize=1053296 CPUtime=0
/proc/29100/task/29110/stat : 29110 (java) S 29097 29097 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 42777918 1078575104 166814 18446744073709551615 4194304 4228012 140736522412752 139778774476640 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.16
Current children cumulated vsize (KiB) 1062464

[startup+296.801 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29110
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=1054324 CPUtime=293.63
/proc/29100/stat : 29100 (java) S 29097 29097 32685 0 -1 4202496 171448 0 15 0 29307 56 0 0 20 0 11 0 42776778 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 140736522403776 139778883874421 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 263581 166827 2393 9 0 250931 0
[pid=29100/tid=29101] ppid=29097 vsize=1054324 CPUtime=269.97
/proc/29100/task/29101/stat : 29101 (java) S 29097 29097 32685 0 -1 4202560 47812 0 3 0 26975 22 0 0 20 0 11 0 42776784 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778890408880 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 14 0 0
[pid=29100/tid=29102] ppid=29097 vsize=1054324 CPUtime=19.23
/proc/29100/task/29102/stat : 29102 (java) S 29097 29097 32685 0 -1 4202560 102655 0 0 0 1894 29 0 0 20 0 11 0 42776794 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778783046784 139778883888715 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29103] ppid=29097 vsize=1054324 CPUtime=0
/proc/29100/task/29103/stat : 29103 (java) S 29097 29097 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 42776795 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778781992976 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29104] ppid=29097 vsize=1054324 CPUtime=0
/proc/29100/task/29104/stat : 29104 (java) S 29097 29097 32685 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 42776795 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778780940192 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29105] ppid=29097 vsize=1054324 CPUtime=0
/proc/29100/task/29105/stat : 29105 (java) S 29097 29097 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 42776797 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778779888624 139778883896352 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29106] ppid=29097 vsize=1054324 CPUtime=2.03
/proc/29100/task/29106/stat : 29106 (java) S 29097 29097 32685 0 -1 4202560 11150 0 0 0 200 3 0 0 20 0 11 0 42776797 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778778835984 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29107] ppid=29097 vsize=1054324 CPUtime=2.22
/proc/29100/task/29107/stat : 29107 (java) S 29097 29097 32685 0 -1 4202560 8515 0 0 0 221 1 0 0 20 0 11 0 42776797 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778777783312 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29108] ppid=29097 vsize=1054324 CPUtime=0
/proc/29100/task/29108/stat : 29108 (java) S 29097 29097 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 42776797 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778776730832 139778883887828 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=29100/tid=29109] ppid=29097 vsize=1054324 CPUtime=0.14
/proc/29100/task/29109/stat : 29109 (java) S 29097 29097 32685 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 42776797 1079627776 166827 18446744073709551615 4194304 4228012 140736522412752 139778775678336 139778883888715 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 293.63
Current children cumulated vsize (KiB) 1063492

[startup+297.207 s]
/proc/loadavg: 1.00 1.04 1.04 2/72 29111
/proc/meminfo: memFree=10152/1022884 swapFree=0/0
[pid=29097] ppid=29096 vsize=9168 CPUtime=0
/proc/29097/stat : 29097 (p2cudf-full-1.1) S 29096 29097 32685 0 -1 4202496 454 290 2 1 0 0 0 0 20 0 1 0 42776775 9388032 330 18446744073709551615 4194304 5129932 140735722643504 140735722641648 139652770141278 0 65536 16781316 65538 0 0 0 17 0 0 0 2 0 0
/proc/29097/statm: 2292 330 280 229 0 52 0
[pid=29100] ppid=29097 vsize=0 CPUtime=293.85
/proc/29100/stat : 29100 (java) Z 29097 29097 32685 0 -1 4203532 171461 0 15 0 29321 64 0 0 20 0 2 0 42776778 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 5 0 0
/proc/29100/statm: 0 0 0 0 0 0 0
[pid=29100/tid=29111] ppid=29097 vsize=0 CPUtime=0.18
/proc/29100/task/29111/stat : 29111 (java) R 29097 29097 32685 0 -1 4203588 8 0 0 0 10 8 0 0 20 0 2 0 42806423 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) 293.85
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 297.212
CPU time (s): 293.866
CPU user time (s): 293.218
CPU system time (s): 0.64804
CPU usage (%): 98.8745
Max. virtual memory (cumulated for all children) (KiB): 1064288

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 293.218
system time used= 0.64804
maximum resident set size= 667352
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 172218
page faults= 18
swaps= 0
block input operations= 70544
block output operations= 544
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7369
involuntary context switches= 12986

runsolver used 0.80005 second user time and 1.72411 second system time

The end