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/201108291842/p2cudf-user-1.13/rand21.cudf.dudf-random.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-random//rand21.cudf /home/misc2010/tmp/201108291842/p2cudf-user-1.13/rand21.cudf.dudf-random.result -notuptodate,-removed,-changed 

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.96 0.92 0.83 4/36 18094
/proc/meminfo: memFree=852180/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2568 CPUtime=0
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 352 0 0 0 0 0 0 0 24 0 1 0 46002231 2629632 271 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/18092/statm: 642 271 231 194 0 29 0
[pid=18093] ppid=18092 vsize=2568 CPUtime=0
/proc/18093/stat : 18093 (p2cudf) R 18092 18091 17511 34816 17511 4202560 94 0 0 0 0 0 0 0 25 0 1 0 46002231 2629632 118 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/18093/statm: 642 118 77 194 0 29 0
[pid=18094] ppid=18093 vsize=2568 CPUtime=0
/proc/18094/stat : 18094 (p2cudf) R 18093 18091 17511 34816 17511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 46002231 2629632 41 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/18094/statm: 642 41 0 194 0 29 0

[startup+0.146285 s]
/proc/loadavg: 0.96 0.92 0.83 4/36 18094
/proc/meminfo: memFree=852180/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.206295 s]
/proc/loadavg: 0.96 0.92 0.83 4/36 18094
/proc/meminfo: memFree=852180/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.306313 s]
/proc/loadavg: 0.96 0.92 0.83 4/36 18094
/proc/meminfo: memFree=852180/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+0.7064 s]
/proc/loadavg: 0.96 0.92 0.83 4/36 18094
/proc/meminfo: memFree=852180/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5136

[startup+1.50662 s]
/proc/loadavg: 0.96 0.92 0.83 5/45 18114
/proc/meminfo: memFree=813208/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=884648 CPUtime=1.45
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 20625 0 1 0 132 13 0 0 25 0 10 0 46002234 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221162 17311 1721 10 0 218253 0
[pid=18105/tid=18106] ppid=18092 vsize=884648 CPUtime=0.77
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 9221 0 1 0 69 8 0 0 25 0 10 0 46002235 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4095608040 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 16 0 10 0 46002236 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 25 0 10 0 46002238 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46002238 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46002240 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=884648 CPUtime=0.32
/proc/18105/task/18111/stat : 18111 (java) R 18092 18091 17511 34816 17511 4202560 5367 0 0 0 30 2 0 0 18 0 10 0 46002240 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4150080727 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=884648 CPUtime=0.33
/proc/18105/task/18112/stat : 18112 (java) R 18092 18091 17511 34816 17511 4202560 4957 0 0 0 31 2 0 0 25 0 10 0 46002241 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46002241 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=884648 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46002241 905879552 17311 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 889784

[startup+3.10705 s]
/proc/loadavg: 1.04 0.94 0.83 2/45 18114
/proc/meminfo: memFree=703840/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=883984 CPUtime=3.04
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 73118 0 1 0 269 35 0 0 25 0 10 0 46002234 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 220996 69653 1740 10 0 218087 0
[pid=18105/tid=18106] ppid=18092 vsize=883984 CPUtime=1.87
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50050 0 1 0 161 26 0 0 25 0 10 0 46002235 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4096171809 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=883984 CPUtime=0.24
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 11336 0 0 0 20 4 0 0 16 0 10 0 46002236 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=883984 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 10 0 46002238 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=883984 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46002238 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=883984 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46002240 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=883984 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 10 0 46002240 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=883984 CPUtime=0.46
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 5060 0 0 0 44 2 0 0 15 0 10 0 46002241 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=883984 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46002241 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=883984 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46002241 905199616 69653 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.07
Current children cumulated vsize (KiB) 889120

[startup+6.30807 s]
/proc/loadavg: 1.04 0.94 0.83 3/46 18115
/proc/meminfo: memFree=524344/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885088 CPUtime=6.23
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 87624 0 1 0 582 41 0 0 25 0 11 0 46002234 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221272 83475 1779 10 0 218363 0
[pid=18105/tid=18106] ppid=18092 vsize=885088 CPUtime=3.26
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50271 0 1 0 299 27 0 0 25 0 11 0 46002235 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4096619429 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885088 CPUtime=0.56
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 24499 0 0 0 48 8 0 0 16 0 11 0 46002236 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885088 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885088 CPUtime=1.94
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6178 0 0 0 191 3 0 0 15 0 11 0 46002241 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18115] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18115/stat : 18115 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46002769 906330112 83475 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.26
Current children cumulated vsize (KiB) 890224

[startup+12.7099 s]
/proc/loadavg: 1.04 0.94 0.83 2/46 18115
/proc/meminfo: memFree=523856/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885088 CPUtime=12.61
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 87690 0 1 0 1220 41 0 0 25 0 11 0 46002234 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221272 83541 1784 10 0 218363 0
[pid=18105/tid=18106] ppid=18092 vsize=885088 CPUtime=9.08
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50279 0 1 0 881 27 0 0 25 0 11 0 46002235 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4096575234 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885088 CPUtime=0.56
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 24499 0 0 0 48 8 0 0 16 0 11 0 46002236 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885088 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885088 CPUtime=2.51
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6236 0 0 0 248 3 0 0 15 0 11 0 46002241 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) R 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18115] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18115/stat : 18115 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46002769 906330112 83541 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.64
Current children cumulated vsize (KiB) 890224

[startup+25.5044 s]
/proc/loadavg: 1.03 0.94 0.84 2/49 18120
/proc/meminfo: memFree=522580/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885088 CPUtime=25.35
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 87693 0 1 0 2494 41 0 0 25 0 11 0 46002234 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221272 83544 1784 10 0 218363 0
[pid=18105/tid=18106] ppid=18092 vsize=885088 CPUtime=21.68
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50280 0 1 0 2141 27 0 0 25 0 11 0 46002235 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4096648563 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885088 CPUtime=0.56
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 24499 0 0 0 48 8 0 0 15 0 11 0 46002236 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885088 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885088 CPUtime=2.63
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6238 0 0 0 260 3 0 0 15 0 11 0 46002241 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18115] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18115/stat : 18115 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46002769 906330112 83544 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.38
Current children cumulated vsize (KiB) 890224

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

[startup+31.9064 s]
/proc/loadavg: 1.03 0.94 0.84 2/49 18122
/proc/meminfo: memFree=489828/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885088 CPUtime=31.71
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 95846 0 1 0 3129 42 0 0 25 0 11 0 46002234 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221272 91697 1788 10 0 218363 0
[pid=18105/tid=18106] ppid=18092 vsize=885088 CPUtime=27.28
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50280 0 1 0 2701 27 0 0 25 0 11 0 46002235 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4096610636 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885088 CPUtime=0.93
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 32615 0 0 0 85 8 0 0 15 0 11 0 46002236 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885088 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885088 CPUtime=3.02
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6275 0 0 0 299 3 0 0 16 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18115] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18115/stat : 18115 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46002769 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 31.74
Current children cumulated vsize (KiB) 890224

[startup+33.5069 s]
/proc/loadavg: 1.03 0.94 0.84 2/49 18122
/proc/meminfo: memFree=489844/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885088 CPUtime=33.31
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 95846 0 1 0 3289 42 0 0 25 0 11 0 46002234 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221272 91697 1788 10 0 218363 0
[pid=18105/tid=18106] ppid=18092 vsize=885088 CPUtime=28.87
/proc/18105/task/18106/stat : 18106 (java) R 18092 18091 17511 34816 17511 4202560 50280 0 1 0 2860 27 0 0 25 0 11 0 46002235 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4096595664 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885088 CPUtime=0.93
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 32615 0 0 0 85 8 0 0 15 0 11 0 46002236 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885088 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885088 CPUtime=3.02
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6275 0 0 0 299 3 0 0 16 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18115] ppid=18092 vsize=885088 CPUtime=0
/proc/18105/task/18115/stat : 18115 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46002769 906330112 91697 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 33.34
Current children cumulated vsize (KiB) 890224

[startup+35.107 s]
/proc/loadavg: 1.03 0.94 0.84 1/49 18123
/proc/meminfo: memFree=489580/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885412 CPUtime=34.86
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 95860 0 1 0 3443 43 0 0 25 0 11 0 46002234 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221353 91711 1789 10 0 218444 0
[pid=18105/tid=18106] ppid=18092 vsize=885412 CPUtime=30.38
/proc/18105/task/18106/stat : 18106 (java) S 18092 18091 17511 34816 17511 4202560 50289 0 1 0 3011 27 0 0 25 0 11 0 46002235 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885412 CPUtime=0.94
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 32615 0 0 0 85 9 0 0 16 0 11 0 46002236 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885412 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885412 CPUtime=3.06
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6275 0 0 0 303 3 0 0 16 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18123] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18123/stat : 18123 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46005736 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 34.89
Current children cumulated vsize (KiB) 890548

[startup+35.5071 s]
/proc/loadavg: 1.03 0.94 0.84 1/49 18123
/proc/meminfo: memFree=489580/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885412 CPUtime=34.86
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 95860 0 1 0 3443 43 0 0 25 0 11 0 46002234 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221353 91711 1789 10 0 218444 0
[pid=18105/tid=18106] ppid=18092 vsize=885412 CPUtime=30.38
/proc/18105/task/18106/stat : 18106 (java) S 18092 18091 17511 34816 17511 4202560 50289 0 1 0 3011 27 0 0 25 0 11 0 46002235 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885412 CPUtime=0.94
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 32615 0 0 0 85 9 0 0 16 0 11 0 46002236 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885412 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885412 CPUtime=3.06
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6275 0 0 0 303 3 0 0 16 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18123] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18123/stat : 18123 (java) S 18092 18091 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46005736 906661888 91711 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 34.89
Current children cumulated vsize (KiB) 890548

[startup+35.7105 s]
/proc/loadavg: 1.03 0.94 0.84 1/49 18123
/proc/meminfo: memFree=489580/1048576 swapFree=0/0
[pid=18091] ppid=18090 vsize=2564 CPUtime=0
/proc/18091/stat : 18091 (p2cudf-user-1.1) S 18090 18091 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 24 0 1 0 46002230 2625536 269 1283457024 134512640 135304128 4290960944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18091/statm: 641 269 230 194 0 28 0
[pid=18092] ppid=18091 vsize=2572 CPUtime=0.03
/proc/18092/stat : 18092 (p2cudf) S 18091 18091 17511 34816 17511 4202496 391 3014 0 1 0 0 2 1 25 0 1 0 46002231 2633728 280 1283457024 134512640 135304128 4292954176 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/18092/statm: 643 280 239 194 0 30 0
[pid=18105] ppid=18092 vsize=885412 CPUtime=35.01
/proc/18105/stat : 18105 (java) S 18092 18091 17511 34816 17511 4202496 95861 0 1 0 3458 43 0 0 25 0 11 0 46002234 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/18105/statm: 221353 91712 1789 10 0 218444 0
[pid=18105/tid=18106] ppid=18092 vsize=885412 CPUtime=30.38
/proc/18105/task/18106/stat : 18106 (java) S 18092 18091 17511 34816 17511 4202560 50289 0 1 0 3011 27 0 0 25 0 11 0 46002235 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18107] ppid=18092 vsize=885412 CPUtime=0.94
/proc/18105/task/18107/stat : 18107 (java) S 18092 18091 17511 34816 17511 4202560 32615 0 0 0 85 9 0 0 16 0 11 0 46002236 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18108] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18108/stat : 18108 (java) S 18092 18091 17511 34816 17511 4202560 10 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18109] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18109/stat : 18109 (java) S 18092 18091 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46002238 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18110] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18110/stat : 18110 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002240 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18111] ppid=18092 vsize=885412 CPUtime=0.45
/proc/18105/task/18111/stat : 18111 (java) S 18092 18091 17511 34816 17511 4202560 5595 0 0 0 43 2 0 0 16 0 11 0 46002240 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18112] ppid=18092 vsize=885412 CPUtime=3.11
/proc/18105/task/18112/stat : 18112 (java) S 18092 18091 17511 34816 17511 4202560 6275 0 0 0 308 3 0 0 15 0 11 0 46002241 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18113] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18113/stat : 18113 (java) S 18092 18091 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46002241 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18114] ppid=18092 vsize=885412 CPUtime=0
/proc/18105/task/18114/stat : 18114 (java) S 18092 18091 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46002241 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=18105/tid=18123] ppid=18092 vsize=885412 CPUtime=0.1
/proc/18105/task/18123/stat : 18123 (java) R 18092 18091 17511 34816 17511 4202560 5 0 0 0 10 0 0 0 21 0 11 0 46005736 906661888 91712 1283457024 134512640 134550932 4291814848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 35.04
Current children cumulated vsize (KiB) 890548

Child status: 0
Real time (s): 35.779
CPU time (s): 35.1102
CPU user time (s): 34.6102
CPU system time (s): 0.500031
CPU usage (%): 98.1309
Max. virtual memory (cumulated for all children) (KiB): 890628

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 34.6102
system time used= 0.500031
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 99663
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= 1560
involuntary context switches= 1985

runsolver used 0 second user time and 0.020001 second system time

The end