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/201108241238/p2cudf-trendy-1.13/27000e82-c5c4-11df-a7c5-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/dudf-real//27000e82-c5c4-11df-a7c5-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/27000e82-c5c4-11df-a7c5-00163e3d3b7c.cudf.dudf-real.result 

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: 1.11 1.06 1.01 5/37 11198
/proc/meminfo: memFree=586428/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2572 CPUtime=0
/proc/11197/stat : 11197 (p2cudf) R 11196 11196 1511 34817 1511 4202496 347 0 0 0 0 0 0 0 25 0 1 0 2577110 2633728 272 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/11197/statm: 643 272 231 194 0 30 0
[pid=11198] ppid=11197 vsize=2572 CPUtime=0
/proc/11198/stat : 11198 (p2cudf) R 11197 11196 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 2577110 2633728 41 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/11198/statm: 643 41 0 194 0 30 0

[startup+0.145674 s]
/proc/loadavg: 1.11 1.06 1.01 5/37 11198
/proc/meminfo: memFree=586428/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.20568 s]
/proc/loadavg: 1.11 1.06 1.01 5/37 11198
/proc/meminfo: memFree=586428/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.305702 s]
/proc/loadavg: 1.11 1.06 1.01 5/37 11198
/proc/meminfo: memFree=586428/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.705818 s]
/proc/loadavg: 1.11 1.06 1.01 5/37 11198
/proc/meminfo: memFree=586428/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+1.50607 s]
/proc/loadavg: 1.18 1.08 1.02 4/47 11219
/proc/meminfo: memFree=540768/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=885308 CPUtime=1.46
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 23432 0 1 0 140 6 0 0 25 0 10 0 2577113 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 221327 20251 1729 10 0 218418 0
[pid=11210/tid=11211] ppid=11197 vsize=885308 CPUtime=0.79
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 12119 0 1 0 76 3 0 0 25 0 10 0 2577114 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4095806334 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 17 0 10 0 2577115 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 25 0 10 0 2577116 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 2577116 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=885308 CPUtime=0.43
/proc/11210/task/11216/stat : 11216 (java) R 11197 11196 1511 34817 1511 4202560 8391 0 0 0 40 3 0 0 25 0 10 0 2577118 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=885308 CPUtime=0.22
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 1840 0 0 0 22 0 0 0 22 0 10 0 2577118 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=885308 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 2577118 906555392 20251 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 890456

[startup+3.10665 s]
/proc/loadavg: 1.18 1.08 1.02 2/47 11219
/proc/meminfo: memFree=412180/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=883176 CPUtime=3.06
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 73949 0 1 0 286 20 0 0 25 0 10 0 2577113 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220794 70508 1743 10 0 217885 0
[pid=11210/tid=11211] ppid=11197 vsize=883176 CPUtime=1.71
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50091 0 1 0 158 13 0 0 25 0 10 0 2577114 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=883176 CPUtime=0.25
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 12296 0 0 0 22 3 0 0 16 0 10 0 2577115 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=883176 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 2577116 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=883176 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 2577116 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=883176 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=883176 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 10 0 2577118 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=883176 CPUtime=0.6
/proc/11210/task/11217/stat : 11217 (java) R 11197 11196 1511 34817 1511 4202560 2037 0 0 0 60 0 0 0 16 0 10 0 2577118 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4153625998 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=883176 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=883176 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 2577118 904372224 70508 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 888324

[startup+6.31773 s]
/proc/loadavg: 1.18 1.08 1.02 2/47 11219
/proc/meminfo: memFree=260652/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=883500 CPUtime=6.27
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 88462 0 1 0 602 25 0 0 25 0 11 0 2577113 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220875 83001 1779 10 0 217966 0
[pid=11210/tid=11211] ppid=11197 vsize=883500 CPUtime=2.94
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50271 0 1 0 281 13 0 0 25 0 11 0 2577114 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=883500 CPUtime=0.56
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 24402 0 0 0 48 8 0 0 16 0 11 0 2577115 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=883500 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=883500 CPUtime=2.28
/proc/11210/task/11217/stat : 11217 (java) R 11197 11196 1511 34817 1511 4202560 4260 0 0 0 228 0 0 0 15 0 11 0 2577118 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4147516766 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 904704000 83001 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 888648

[startup+12.7096 s]
/proc/loadavg: 1.15 1.07 1.02 3/48 11221
/proc/meminfo: memFree=260156/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=883500 CPUtime=12.65
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 88516 0 1 0 1240 25 0 0 25 0 11 0 2577113 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220875 83055 1782 10 0 217966 0
[pid=11210/tid=11211] ppid=11197 vsize=883500 CPUtime=8.92
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50279 0 1 0 879 13 0 0 25 0 11 0 2577114 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4096815256 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=883500 CPUtime=0.56
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 24402 0 0 0 48 8 0 0 16 0 11 0 2577115 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=883500 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=883500 CPUtime=2.68
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4306 0 0 0 268 0 0 0 15 0 11 0 2577118 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11220] ppid=11197 vsize=883500 CPUtime=0
/proc/11210/task/11220/stat : 11220 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 2577682 904704000 83055 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.68
Current children cumulated vsize (KiB) 888648

[startup+25.5134 s]
/proc/loadavg: 1.13 1.07 1.01 3/48 11222
/proc/meminfo: memFree=261024/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882092 CPUtime=25.45
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 88531 0 1 0 2520 25 0 0 25 0 11 0 2577113 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220523 82718 1784 10 0 217614 0
[pid=11210/tid=11211] ppid=11197 vsize=882092 CPUtime=21.47
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50285 0 1 0 2134 13 0 0 25 0 11 0 2577114 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4095671050 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882092 CPUtime=0.56
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 24402 0 0 0 48 8 0 0 15 0 11 0 2577115 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882092 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882092 CPUtime=2.93
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4315 0 0 0 293 0 0 0 15 0 11 0 2577118 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11220] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11220/stat : 11220 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 2577682 903262208 82718 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.48
Current children cumulated vsize (KiB) 887240

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

[startup+27.0137 s]
/proc/loadavg: 1.12 1.07 1.01 2/48 11222
/proc/meminfo: memFree=261024/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882092 CPUtime=26.95
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 97722 0 1 0 2667 28 0 0 25 0 11 0 2577113 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220523 91909 1788 10 0 217614 0
[pid=11210/tid=11211] ppid=11197 vsize=882092 CPUtime=22.51
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50286 0 1 0 2238 13 0 0 25 0 11 0 2577114 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4095671050 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882092 CPUtime=0.97
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 33588 0 0 0 87 10 0 0 16 0 11 0 2577115 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882092 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882092 CPUtime=2.96
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4319 0 0 0 296 0 0 0 16 0 11 0 2577118 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11220] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11220/stat : 11220 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 2577682 903262208 91909 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 26.98
Current children cumulated vsize (KiB) 887240

[startup+27.8139 s]
/proc/loadavg: 1.12 1.07 1.01 2/48 11222
/proc/meminfo: memFree=224568/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882092 CPUtime=27.75
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 97724 0 1 0 2747 28 0 0 25 0 11 0 2577113 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220523 91911 1788 10 0 217614 0
[pid=11210/tid=11211] ppid=11197 vsize=882092 CPUtime=23.29
/proc/11210/task/11211/stat : 11211 (java) R 11197 11196 1511 34817 1511 4202560 50286 0 1 0 2316 13 0 0 25 0 11 0 2577114 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4096621177 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882092 CPUtime=0.97
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 33588 0 0 0 87 10 0 0 16 0 11 0 2577115 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882092 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882092 CPUtime=2.98
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4321 0 0 0 298 0 0 0 16 0 11 0 2577118 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11220] ppid=11197 vsize=882092 CPUtime=0
/proc/11210/task/11220/stat : 11220 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 2577682 903262208 91911 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 27.78
Current children cumulated vsize (KiB) 887240

[startup+28.6075 s]
/proc/loadavg: 1.12 1.07 1.01 1/48 11224
/proc/meminfo: memFree=224444/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882416 CPUtime=28.3
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 97739 0 1 0 2802 28 0 0 25 0 11 0 2577113 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220604 91926 1789 10 0 217695 0
[pid=11210/tid=11211] ppid=11197 vsize=882416 CPUtime=23.79
/proc/11210/task/11211/stat : 11211 (java) S 11197 11196 1511 34817 1511 4202560 50294 0 1 0 2366 13 0 0 25 0 11 0 2577114 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882416 CPUtime=0.98
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 33588 0 0 0 87 11 0 0 16 0 11 0 2577115 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882416 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882416 CPUtime=3.04
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4323 0 0 0 304 0 0 0 15 0 11 0 2577118 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11224] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11224/stat : 11224 (java) S 11197 11196 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 2579947 903593984 91926 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 28.33
Current children cumulated vsize (KiB) 887564

[startup+29.0155 s]
/proc/loadavg: 1.12 1.07 1.01 1/48 11224
/proc/meminfo: memFree=224444/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882416 CPUtime=28.45
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 97741 0 1 0 2811 34 0 0 25 0 11 0 2577113 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220604 91928 1789 10 0 217695 0
[pid=11210/tid=11211] ppid=11197 vsize=882416 CPUtime=23.79
/proc/11210/task/11211/stat : 11211 (java) S 11197 11196 1511 34817 1511 4202560 50294 0 1 0 2366 13 0 0 25 0 11 0 2577114 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882416 CPUtime=0.98
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 33588 0 0 0 87 11 0 0 16 0 11 0 2577115 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 2577116 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 2577116 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882416 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 11 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882416 CPUtime=3.09
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4323 0 0 0 309 0 0 0 16 0 11 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11224] ppid=11197 vsize=882416 CPUtime=0.08
/proc/11210/task/11224/stat : 11224 (java) R 11197 11196 1511 34817 1511 4202560 6 0 0 0 3 5 0 0 21 0 11 0 2579947 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 28.48
Current children cumulated vsize (KiB) 887564

[startup+29.1075 s]
/proc/loadavg: 1.12 1.07 1.01 1/48 11224
/proc/meminfo: memFree=224444/1048576 swapFree=0/0
[pid=11196] ppid=11195 vsize=2572 CPUtime=0
/proc/11196/stat : 11196 (p2cudf-trendy-1) S 11195 11196 1511 34817 1511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 2577110 2633728 271 1283457024 134512640 135304128 4290225520 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11196/statm: 643 271 230 194 0 30 0
[pid=11197] ppid=11196 vsize=2576 CPUtime=0.03
/proc/11197/stat : 11197 (p2cudf) S 11196 11196 1511 34817 1511 4202496 393 3014 0 1 0 0 1 2 25 0 1 0 2577110 2637824 281 1283457024 134512640 135304128 4292355936 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/11197/statm: 644 281 239 194 0 31 0
[pid=11210] ppid=11197 vsize=882416 CPUtime=28.49
/proc/11210/stat : 11210 (java) S 11197 11196 1511 34817 1511 4202496 97741 0 1 0 2811 38 0 0 25 0 10 0 2577113 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/11210/statm: 220604 91928 1789 10 0 217695 0
[pid=11210/tid=11211] ppid=11197 vsize=882416 CPUtime=23.79
/proc/11210/task/11211/stat : 11211 (java) S 11197 11196 1511 34817 1511 4202560 50294 0 1 0 2366 13 0 0 19 0 10 0 2577114 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11212] ppid=11197 vsize=882416 CPUtime=0.98
/proc/11210/task/11212/stat : 11212 (java) S 11197 11196 1511 34817 1511 4202560 33588 0 0 0 87 11 0 0 16 0 10 0 2577115 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 0 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11213] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11213/stat : 11213 (java) S 11197 11196 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 2577116 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11214] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11214/stat : 11214 (java) S 11197 11196 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 2577116 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11215] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11215/stat : 11215 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11216] ppid=11197 vsize=882416 CPUtime=0.47
/proc/11210/task/11216/stat : 11216 (java) S 11197 11196 1511 34817 1511 4202560 8447 0 0 0 44 3 0 0 25 0 10 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11217] ppid=11197 vsize=882416 CPUtime=3.09
/proc/11210/task/11217/stat : 11217 (java) S 11197 11196 1511 34817 1511 4202560 4323 0 0 0 309 0 0 0 15 0 10 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11218] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11218/stat : 11218 (java) S 11197 11196 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
[pid=11210/tid=11219] ppid=11197 vsize=882416 CPUtime=0
/proc/11210/task/11219/stat : 11219 (java) S 11197 11196 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 2577118 903593984 91928 1283457024 134512640 134550932 4289684336 18446744073709551615 4294960130 0 4 0 16800975 18446612133173263424 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 28.52
Current children cumulated vsize (KiB) 887564

Child status: 0
Real time (s): 29.1348
CPU time (s): 28.5538
CPU user time (s): 28.1298
CPU system time (s): 0.424026
CPU usage (%): 98.0057
Max. virtual memory (cumulated for all children) (KiB): 890976

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 28.1298
system time used= 0.424026
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 101545
page faults= 2
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 1503
involuntary context switches= 1690

runsolver used 0 second user time and 0 second system time

The end