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/201012070034/p2cudf-trendy-1.9/randeb343c.cudf.easy.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/easy//randeb343c.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/randeb343c.cudf.easy.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: 0.99 0.98 0.99 3/42 7027
/proc/meminfo: memFree=727176/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2568 CPUtime=0
/proc/7026/stat : 7026 (p2cudf) R 7025 7025 4159 34819 4229 4202496 347 0 0 0 0 0 0 0 25 0 1 0 885639128 2629632 273 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/7026/statm: 642 273 234 194 0 28 0
[pid=7027] ppid=7026 vsize=2568 CPUtime=0
/proc/7027/stat : 7027 (p2cudf) R 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 1 0 885639128 2629632 39 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/7027/statm: 642 39 0 194 0 28 0

[startup+0.209932 s]
/proc/loadavg: 0.99 0.98 0.99 3/42 7027
/proc/meminfo: memFree=727176/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.309952 s]
/proc/loadavg: 0.99 0.98 0.99 3/42 7027
/proc/meminfo: memFree=727176/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.40997 s]
/proc/loadavg: 0.99 0.98 0.99 3/42 7027
/proc/meminfo: memFree=727176/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.710059 s]
/proc/loadavg: 0.99 0.98 0.99 3/42 7027
/proc/meminfo: memFree=727176/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+1.51029 s]
/proc/loadavg: 0.99 0.98 0.99 3/52 7048
/proc/meminfo: memFree=684008/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=866908 CPUtime=1.47
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 22272 0 1 0 137 10 0 0 25 0 10 0 885639132 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 216727 21025 1732 10 0 213817 0
[pid=7039/tid=7040] ppid=7026 vsize=866908 CPUtime=0.91
/proc/7039/task/7040/stat : 7040 (java) R 7026 7025 4159 34819 4229 4202560 16957 0 1 0 81 10 0 0 25 0 10 0 885639132 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4095243460 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7041/stat : 7041 (java) S 7026 7025 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 17 0 10 0 885639134 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 885639134 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 885639134 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 885639137 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=866908 CPUtime=0.28
/proc/7039/task/7045/stat : 7045 (java) R 7026 7025 4159 34819 4229 4202560 2646 0 0 0 28 0 0 0 23 0 10 0 885639137 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4147651267 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=866908 CPUtime=0.26
/proc/7039/task/7046/stat : 7046 (java) R 7026 7025 4159 34819 4229 4202560 1557 0 0 0 26 0 0 0 21 0 10 0 885639137 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4149681618 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 10 0 885639137 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=866908 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 885639137 887713792 21025 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 872052

[startup+3.11079 s]
/proc/loadavg: 1.07 1.00 0.99 3/52 7048
/proc/meminfo: memFree=589396/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=876760 CPUtime=3.07
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 46466 0 1 0 289 18 0 0 25 0 10 0 885639132 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 219190 43514 1762 10 0 216280 0
[pid=7039/tid=7040] ppid=7026 vsize=876760 CPUtime=1.42
/proc/7039/task/7040/stat : 7040 (java) R 7026 7025 4159 34819 4229 4202560 37031 0 1 0 126 16 0 0 25 0 10 0 885639132 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7041/stat : 7041 (java) S 7026 7025 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 16 0 10 0 885639134 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 885639134 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 885639134 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 885639137 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=876760 CPUtime=0.69
/proc/7039/task/7045/stat : 7045 (java) S 7026 7025 4159 34819 4229 4202560 3068 0 0 0 69 0 0 0 16 0 10 0 885639137 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=876760 CPUtime=0.93
/proc/7039/task/7046/stat : 7046 (java) R 7026 7025 4159 34819 4229 4202560 5255 0 0 0 92 1 0 0 22 0 10 0 885639137 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4152143180 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 10 0 885639137 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=876760 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 885639137 897802240 43514 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 881904

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

[startup+3.31084 s]
/proc/loadavg: 1.07 1.00 0.99 3/52 7048
/proc/meminfo: memFree=589396/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=877104 CPUtime=3.27
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 48224 0 1 0 308 19 0 0 25 0 10 0 885639132 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 219276 44894 1762 10 0 216366 0
[pid=7039/tid=7040] ppid=7026 vsize=877104 CPUtime=1.49
/proc/7039/task/7040/stat : 7040 (java) R 7026 7025 4159 34819 4229 4202560 38314 0 1 0 132 17 0 0 25 0 10 0 885639132 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7041/stat : 7041 (java) S 7026 7025 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 15 0 10 0 885639134 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 885639134 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 885639134 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 885639137 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=877104 CPUtime=0.77
/proc/7039/task/7045/stat : 7045 (java) R 7026 7025 4159 34819 4229 4202560 3079 0 0 0 77 0 0 0 16 0 10 0 885639137 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4151317645 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=877104 CPUtime=0.99
/proc/7039/task/7046/stat : 7046 (java) R 7026 7025 4159 34819 4229 4202560 5719 0 0 0 98 1 0 0 22 0 10 0 885639137 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4149688060 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 10 0 885639137 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=877104 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 10 0 885639137 898154496 44894 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.3
Current children cumulated vsize (KiB) 882248

[startup+4.93133 s]
/proc/loadavg: 1.07 1.00 0.99 4/53 7050
/proc/meminfo: memFree=514492/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=874396 CPUtime=4.88
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 65679 0 1 0 460 28 0 0 25 0 12 0 885639132 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 218599 61684 1790 10 0 215689 0
[pid=7039/tid=7040] ppid=7026 vsize=874396 CPUtime=2.15
/proc/7039/task/7040/stat : 7040 (java) R 7026 7025 4159 34819 4229 4202560 50435 0 1 0 189 26 0 0 25 0 12 0 885639132 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=874396 CPUtime=0.12
/proc/7039/task/7041/stat : 7041 (java) R 7026 7025 4159 34819 4229 4202560 5192 0 0 0 11 1 0 0 15 0 12 0 885639134 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4148963584 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 12 0 885639134 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 12 0 885639134 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 12 0 885639137 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=874396 CPUtime=1.21
/proc/7039/task/7045/stat : 7045 (java) S 7026 7025 4159 34819 4229 4202560 3151 0 0 0 121 0 0 0 15 0 12 0 885639137 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=874396 CPUtime=1.39
/proc/7039/task/7046/stat : 7046 (java) S 7026 7025 4159 34819 4229 4202560 5789 0 0 0 138 1 0 0 21 0 12 0 885639137 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 12 0 885639137 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 12 0 885639137 895381504 61684 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.91
Current children cumulated vsize (KiB) 879540

[startup+5.3051 s]
/proc/loadavg: 1.07 1.00 0.99 4/53 7050
/proc/meminfo: memFree=514492/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=874396 CPUtime=5.07
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 71323 0 1 0 475 32 0 0 25 0 11 0 885639132 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 218599 67328 1791 10 0 215689 0
[pid=7039/tid=7040] ppid=7026 vsize=874396 CPUtime=2.16
/proc/7039/task/7040/stat : 7040 (java) S 7026 7025 4159 34819 4229 4202560 50442 0 1 0 190 26 0 0 25 0 11 0 885639132 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=874396 CPUtime=0.26
/proc/7039/task/7041/stat : 7041 (java) S 7026 7025 4159 34819 4229 4202560 10823 0 0 0 22 4 0 0 15 0 11 0 885639134 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 885639134 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 885639134 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 885639137 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=874396 CPUtime=1.21
/proc/7039/task/7045/stat : 7045 (java) S 7026 7025 4159 34819 4229 4202560 3151 0 0 0 121 0 0 0 15 0 11 0 885639137 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=874396 CPUtime=1.41
/proc/7039/task/7046/stat : 7046 (java) S 7026 7025 4159 34819 4229 4202560 5789 0 0 0 140 1 0 0 18 0 11 0 885639137 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 885639137 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 885639137 895381504 67328 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.1
Current children cumulated vsize (KiB) 879540

[startup+5.70733 s]
/proc/loadavg: 1.07 1.00 0.99 1/53 7054
/proc/meminfo: memFree=463908/1048576 swapFree=2077680/2097144
[pid=7025] ppid=7024 vsize=2572 CPUtime=0
/proc/7025/stat : 7025 (p2cudf-trendy-1) S 7024 7025 4159 34819 4229 4202496 376 0 0 0 0 0 0 0 25 0 1 0 885639128 2633728 273 1283457024 134512640 135304128 4294185328 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7025/statm: 643 273 233 194 0 29 0
[pid=7026] ppid=7025 vsize=2572 CPUtime=0.03
/proc/7026/stat : 7026 (p2cudf) S 7025 7025 4159 34819 4229 4202496 391 2995 0 1 0 0 2 1 25 0 1 0 885639128 2633728 281 1283457024 134512640 135304128 4292755456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7026/statm: 643 281 241 194 0 29 0
[pid=7039] ppid=7026 vsize=874396 CPUtime=5.15
/proc/7039/stat : 7039 (java) S 7026 7025 4159 34819 4229 4202496 71324 0 1 0 482 33 0 0 25 0 11 0 885639132 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7039/statm: 218599 67329 1791 10 0 215689 0
[pid=7039/tid=7040] ppid=7026 vsize=874396 CPUtime=2.16
/proc/7039/task/7040/stat : 7040 (java) S 7026 7025 4159 34819 4229 4202560 50442 0 1 0 190 26 0 0 25 0 11 0 885639132 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7041] ppid=7026 vsize=874396 CPUtime=0.26
/proc/7039/task/7041/stat : 7041 (java) S 7026 7025 4159 34819 4229 4202560 10823 0 0 0 22 4 0 0 15 0 11 0 885639134 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 0 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7042] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7042/stat : 7042 (java) S 7026 7025 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 885639134 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7043] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7043/stat : 7043 (java) S 7026 7025 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 885639134 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7044] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7044/stat : 7044 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 885639137 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7045] ppid=7026 vsize=874396 CPUtime=1.21
/proc/7039/task/7045/stat : 7045 (java) S 7026 7025 4159 34819 4229 4202560 3151 0 0 0 121 0 0 0 15 0 11 0 885639137 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7046] ppid=7026 vsize=874396 CPUtime=1.46
/proc/7039/task/7046/stat : 7046 (java) S 7026 7025 4159 34819 4229 4202560 5789 0 0 0 145 1 0 0 15 0 11 0 885639137 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7047] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7047/stat : 7047 (java) S 7026 7025 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 885639137 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7048] ppid=7026 vsize=874396 CPUtime=0
/proc/7039/task/7048/stat : 7048 (java) S 7026 7025 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 15 0 11 0 885639137 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 18446612132549435648 0 0 -1 0 0 0 0
[pid=7039/tid=7054] ppid=7026 vsize=874396 CPUtime=0.03
/proc/7039/task/7054/stat : 7054 (java) R 7026 7025 4159 34819 4229 4202560 1 0 0 0 2 1 0 0 20 0 11 0 885639640 895381504 67329 1283457024 134512640 134550932 4289963008 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.18
Current children cumulated vsize (KiB) 879540

Child status: 0
Real time (s): 5.77286
CPU time (s): 5.24833
CPU user time (s): 4.8763
CPU system time (s): 0.372023
CPU usage (%): 90.9139
Max. virtual memory (cumulated for all children) (KiB): 882248

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 4.8763
system time used= 0.372023
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 75114
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= 844
involuntary context switches= 824

runsolver used 0 second user time and 0.004 second system time

The end