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: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201108300820/p2cudf-user-1.13/rand152.cudf.user-upgrades.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/user-upgrades/rand152.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/rand152.cudf.user-upgrades.result +sum(installedsize) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 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
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 0.86 0.99 0.99 1/36 3189
/proc/meminfo: memFree=917408/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=3024 CPUtime=0
/proc/3189/stat : 3189 (runsolver) D 3188 3189 2160 34817 2160 4202560 73 0 0 0 0 0 0 0 25 0 1 0 51389928 3096576 94 1283457024 134512640 134586868 4289647680 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/3189/statm: 756 94 62 19 0 55 0

[startup+0.137803 s]
/proc/loadavg: 0.86 0.99 0.99 1/36 3189
/proc/meminfo: memFree=917408/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.205814 s]
/proc/loadavg: 0.86 0.99 0.99 1/36 3189
/proc/meminfo: memFree=917408/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.305785 s]
/proc/loadavg: 0.86 0.99 0.99 1/36 3189
/proc/meminfo: memFree=917408/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.70583 s]
/proc/loadavg: 0.86 0.99 0.99 1/36 3189
/proc/meminfo: memFree=917408/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+1.51208 s]
/proc/loadavg: 0.86 0.99 0.99 3/48 3212
/proc/meminfo: memFree=883640/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886668 CPUtime=0.78
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 15859 0 104 0 76 2 0 0 22 0 10 0 51389932 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221667 13157 1729 10 0 218758 0
[pid=3203/tid=3204] ppid=3191 vsize=886668 CPUtime=0.22
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 4779 0 15 0 22 0 0 0 25 0 10 0 51389965 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4095514353 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 7 0 0 0 0 0 0 0 17 0 10 0 51389975 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 51389975 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 51389976 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886668 CPUtime=0.29
/proc/3203/task/3209/stat : 3209 (java) R 3191 3189 2160 34817 2160 4202560 5566 0 0 0 28 1 0 0 20 0 10 0 51389986 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4151714837 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886668 CPUtime=0.26
/proc/3203/task/3210/stat : 3210 (java) R 3191 3189 2160 34817 2160 4202560 4513 0 8 0 25 1 0 0 25 0 10 0 51389986 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4152596515 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886668 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 16 0 10 0 51389986 907948032 13157 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.81
Current children cumulated vsize (KiB) 891804

[startup+3.11236 s]
/proc/loadavg: 0.86 0.99 0.99 2/48 3212
/proc/meminfo: memFree=784564/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=885848 CPUtime=2.38
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 72135 0 104 0 212 26 0 0 22 0 10 0 51389932 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221462 69358 1748 10 0 218553 0
[pid=3203/tid=3204] ppid=3191 vsize=885848 CPUtime=1.26
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50041 0 15 0 107 19 0 0 25 0 10 0 51389965 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4095374646 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=885848 CPUtime=0.22
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 10626 0 0 0 19 3 0 0 16 0 10 0 51389975 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=885848 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 10 0 51389975 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=885848 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 10 0 51389976 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=885848 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=885848 CPUtime=0.39
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5579 0 0 0 38 1 0 0 15 0 10 0 51389986 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=885848 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 10 0 51389986 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=885848 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=885848 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 10 0 51389986 907108352 69358 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.41
Current children cumulated vsize (KiB) 890984
heavy processes:

[startup+6.31469 s]
/proc/loadavg: 0.95 1.00 0.99 3/49 3213
/proc/meminfo: memFree=634044/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=5.43
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 72603 0 104 0 517 26 0 0 22 0 11 0 51389932 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 69826 1774 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=2.97
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50272 0 15 0 278 19 0 0 25 0 11 0 51389965 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4096126849 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=0.22
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 10626 0 0 0 19 3 0 0 15 0 11 0 51389975 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.73
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5812 0 0 0 172 1 0 0 15 0 11 0 51389986 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) R 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 69826 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.46
Current children cumulated vsize (KiB) 891308

[startup+12.7061 s]
/proc/loadavg: 0.96 1.00 0.99 2/49 3213
/proc/meminfo: memFree=582468/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=11.82
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 85452 0 104 0 1151 31 0 0 22 0 11 0 51389932 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 82675 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=7.96
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 777 19 0 0 25 0 11 0 51389965 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4095526238 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=1.44
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 23440 0 0 0 136 8 0 0 16 0 11 0 51389975 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.91
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 190 1 0 0 16 0 11 0 51389986 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 82675 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.85
Current children cumulated vsize (KiB) 891308

[startup+25.5089 s]
/proc/loadavg: 0.96 1.00 0.99 3/49 3213
/proc/meminfo: memFree=577508/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=24.61
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 86693 0 104 0 2430 31 0 0 22 0 11 0 51389932 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 83916 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=19.46
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 1927 19 0 0 25 0 11 0 51389965 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4096083105 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=2.74
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 24681 0 0 0 266 8 0 0 15 0 11 0 51389975 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.91
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 190 1 0 0 16 0 11 0 51389986 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 83916 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.64
Current children cumulated vsize (KiB) 891308

[startup+51.1044 s]
/proc/loadavg: 0.98 1.00 0.99 2/49 3213
/proc/meminfo: memFree=575772/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=50.19
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 87074 0 104 0 4987 32 0 0 22 0 11 0 51389932 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 84297 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=42.94
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 4274 20 0 0 25 0 11 0 51389965 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4095997322 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=4.84
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 25062 0 0 0 476 8 0 0 15 0 11 0 51389975 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.91
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 190 1 0 0 16 0 11 0 51389986 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 84297 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.22
Current children cumulated vsize (KiB) 891308

[startup+102.305 s]
/proc/loadavg: 1.10 1.03 1.00 2/49 3213
/proc/meminfo: memFree=573416/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=101.35
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 87623 0 104 0 10101 34 0 0 22 0 11 0 51389932 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 84846 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=89.61
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 8940 21 0 0 25 0 11 0 51389965 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4096097178 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=9.33
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 25611 0 0 0 924 9 0 0 15 0 11 0 51389975 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.91
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 190 1 0 0 16 0 11 0 51389986 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 84846 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.38
Current children cumulated vsize (KiB) 891308

[startup+162.308 s]
/proc/loadavg: 1.08 1.04 1.00 2/51 3220
/proc/meminfo: memFree=571152/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=161.33
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88111 0 104 0 16099 34 0 0 22 0 11 0 51389932 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 85334 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=144.55
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 14434 21 0 0 25 0 11 0 51389965 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4096195748 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=14.36
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26099 0 0 0 1427 9 0 0 16 0 11 0 51389975 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.91
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 190 1 0 0 16 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.36
Current children cumulated vsize (KiB) 891308

[startup+222.311 s]
/proc/loadavg: 1.10 1.04 1.00 2/50 3224
/proc/meminfo: memFree=571052/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=221.28
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88111 0 104 0 22092 36 0 0 22 0 11 0 51389932 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 85334 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=199.43
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 19922 21 0 0 25 0 11 0 51389965 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4096126878 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=19.4
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26099 0 0 0 1930 10 0 0 15 0 11 0 51389975 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.93
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 192 1 0 0 15 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 85334 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.31
Current children cumulated vsize (KiB) 891308

[startup+282.305 s]
/proc/loadavg: 1.03 1.03 1.00 2/49 3224
/proc/meminfo: memFree=569696/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=281.24
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88509 0 104 0 28088 36 0 0 22 0 11 0 51389932 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 85732 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=254.62
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 25441 21 0 0 25 0 11 0 51389965 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4096127021 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=24.17
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26497 0 0 0 2407 10 0 0 16 0 11 0 51389975 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.93
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 192 1 0 0 15 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.27
Current children cumulated vsize (KiB) 891308

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

[startup+283.205 s]
/proc/loadavg: 1.03 1.03 1.00 2/49 3224
/proc/meminfo: memFree=569572/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886172 CPUtime=282.14
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88509 0 104 0 28178 36 0 0 22 0 11 0 51389932 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221543 85732 1783 10 0 218634 0
[pid=3203/tid=3204] ppid=3191 vsize=886172 CPUtime=255.52
/proc/3203/task/3204/stat : 3204 (java) R 3191 3189 2160 34817 2160 4202560 50274 0 15 0 25531 21 0 0 25 0 11 0 51389965 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4096098771 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886172 CPUtime=24.17
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26497 0 0 0 2407 10 0 0 16 0 11 0 51389975 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886172 CPUtime=1.93
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 192 1 0 0 15 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886172 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3213] ppid=3191 vsize=886172 CPUtime=0
/proc/3203/task/3213/stat : 3213 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51390402 907440128 85732 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.17
Current children cumulated vsize (KiB) 891308

[startup+284.804 s]
/proc/loadavg: 1.03 1.03 1.00 2/49 3224
/proc/meminfo: memFree=569572/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886496 CPUtime=283.7
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88521 0 104 0 28334 36 0 0 22 0 11 0 51389932 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221624 85744 1784 10 0 218715 0
[pid=3203/tid=3204] ppid=3191 vsize=886496 CPUtime=257.08
/proc/3203/task/3204/stat : 3204 (java) S 3191 3189 2160 34817 2160 4202560 50281 0 15 0 25687 21 0 0 25 0 11 0 51389965 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886496 CPUtime=24.17
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26497 0 0 0 2407 10 0 0 16 0 11 0 51389975 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886496 CPUtime=1.94
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 193 1 0 0 15 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886496 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.73
Current children cumulated vsize (KiB) 891632

[startup+285.208 s]
/proc/loadavg: 1.03 1.03 1.00 1/49 3225
/proc/meminfo: memFree=569512/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886496 CPUtime=283.7
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88521 0 104 0 28334 36 0 0 22 0 11 0 51389932 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221624 85744 1784 10 0 218715 0
[pid=3203/tid=3204] ppid=3191 vsize=886496 CPUtime=257.08
/proc/3203/task/3204/stat : 3204 (java) S 3191 3189 2160 34817 2160 4202560 50281 0 15 0 25687 21 0 0 25 0 11 0 51389965 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886496 CPUtime=24.17
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26497 0 0 0 2407 10 0 0 16 0 11 0 51389975 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51389975 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51389976 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886496 CPUtime=1.94
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 193 1 0 0 15 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886496 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51389986 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3225] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3225/stat : 3225 (java) S 3191 3189 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51418407 907771904 85744 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.73
Current children cumulated vsize (KiB) 891632

[startup+285.408 s]
/proc/loadavg: 1.03 1.03 1.00 1/49 3225
/proc/meminfo: memFree=569512/1048576 swapFree=0/0
[pid=3189] ppid=3188 vsize=2564 CPUtime=0
/proc/3189/stat : 3189 (p2cudf-user-1.1) S 3188 3189 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 51389928 2625536 270 1283457024 134512640 135304128 4286802496 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3189/statm: 641 270 230 194 0 28 0
[pid=3191] ppid=3189 vsize=2572 CPUtime=0.03
/proc/3191/stat : 3191 (p2cudf) S 3189 3189 2160 34817 2160 4202496 391 3013 0 1 0 0 2 1 25 0 1 0 51389929 2633728 280 1283457024 134512640 135304128 4287591200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3191/statm: 643 280 239 194 0 30 0
[pid=3203] ppid=3191 vsize=886496 CPUtime=283.78
/proc/3203/stat : 3203 (java) S 3191 3189 2160 34817 2160 4202496 88522 0 104 0 28340 38 0 0 22 0 10 0 51389932 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3203/statm: 221624 85745 1784 10 0 218715 0
[pid=3203/tid=3204] ppid=3191 vsize=886496 CPUtime=257.08
/proc/3203/task/3204/stat : 3204 (java) S 3191 3189 2160 34817 2160 4202560 50281 0 15 0 25687 21 0 0 20 0 10 0 51389965 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3205] ppid=3191 vsize=886496 CPUtime=24.17
/proc/3203/task/3205/stat : 3205 (java) S 3191 3189 2160 34817 2160 4202560 26497 0 0 0 2407 10 0 0 16 0 10 0 51389975 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3206] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3206/stat : 3206 (java) S 3191 3189 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 10 0 51389975 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3207] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3207/stat : 3207 (java) S 3191 3189 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 10 0 51389976 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3208] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3208/stat : 3208 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3209] ppid=3191 vsize=886496 CPUtime=1.95
/proc/3203/task/3209/stat : 3209 (java) S 3191 3189 2160 34817 2160 4202560 5845 0 0 0 194 1 0 0 15 0 10 0 51389986 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3210] ppid=3191 vsize=886496 CPUtime=0.48
/proc/3203/task/3210/stat : 3210 (java) S 3191 3189 2160 34817 2160 4202560 4893 0 8 0 46 2 0 0 25 0 10 0 51389986 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3211] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3211/stat : 3211 (java) S 3191 3189 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51389986 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=3203/tid=3212] ppid=3191 vsize=886496 CPUtime=0
/proc/3203/task/3212/stat : 3212 (java) S 3191 3189 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 10 0 51389986 907771904 85745 1283457024 134512640 134550932 4289026464 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.81
Current children cumulated vsize (KiB) 891632

Child status: 0
Real time (s): 285.449
CPU time (s): 283.838
CPU user time (s): 283.43
CPU system time (s): 0.408025
CPU usage (%): 99.4355
Max. virtual memory (cumulated for all children) (KiB): 892584

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.43
system time used= 0.408025
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 92326
page faults= 105
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6570
involuntary context switches= 8939

runsolver used 0 second user time and 0 second system time

The end