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/201103031013/p2cudf-trendy-1.12/4088.cudf.caixa.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/caixa//4088.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/4088.cudf.caixa.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.74 1.62 1.62 5/36 10363
/proc/meminfo: memFree=289764/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2568 CPUtime=0
/proc/10362/stat : 10362 (p2cudf) R 10361 10361 29493 34817 29493 4202496 346 0 0 0 0 0 0 0 25 0 1 0 76214367 2629632 271 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/10362/statm: 642 271 231 194 0 29 0
[pid=10363] ppid=10362 vsize=2568 CPUtime=0
/proc/10363/stat : 10363 (p2cudf) R 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 76214367 2629632 40 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/10363/statm: 642 40 0 194 0 29 0

[startup+0.117668 s]
/proc/loadavg: 1.74 1.62 1.62 5/36 10363
/proc/meminfo: memFree=289764/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.207692 s]
/proc/loadavg: 1.74 1.62 1.62 5/36 10363
/proc/meminfo: memFree=289764/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.307708 s]
/proc/loadavg: 1.74 1.62 1.62 5/36 10363
/proc/meminfo: memFree=289764/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.707805 s]
/proc/loadavg: 1.74 1.62 1.62 5/36 10363
/proc/meminfo: memFree=289764/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+1.50798 s]
/proc/loadavg: 1.74 1.62 1.62 3/46 10384
/proc/meminfo: memFree=230960/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=879028 CPUtime=1.47
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 24123 0 1 0 132 15 0 0 25 0 10 0 76214370 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 219757 20421 1722 10 0 216848 0
[pid=10375/tid=10376] ppid=10362 vsize=879028 CPUtime=0.79
/proc/10375/task/10376/stat : 10376 (java) R 10362 10361 29493 34817 29493 4202560 13649 0 1 0 71 8 0 0 25 0 10 0 76214371 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4095409110 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 18 0 10 0 76214373 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76214373 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76214373 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76214376 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=879028 CPUtime=0.39
/proc/10375/task/10381/stat : 10381 (java) R 10362 10361 29493 34817 29493 4202560 5926 0 0 0 38 1 0 0 24 0 10 0 76214376 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=879028 CPUtime=0.26
/proc/10375/task/10382/stat : 10382 (java) R 10362 10361 29493 34817 29493 4202560 3469 0 0 0 22 4 0 0 18 0 10 0 76214376 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4151896249 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76214376 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=879028 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76214376 900124672 20421 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 884172

[startup+3.10847 s]
/proc/loadavg: 1.74 1.62 1.62 4/46 10384
/proc/meminfo: memFree=166852/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=892712 CPUtime=3.07
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 52204 0 1 0 285 22 0 0 25 0 10 0 76214370 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 223178 45141 1729 10 0 220269 0
[pid=10375/tid=10376] ppid=10362 vsize=892712 CPUtime=1.29
/proc/10375/task/10376/stat : 10376 (java) R 10362 10361 29493 34817 29493 4202560 34550 0 1 0 114 15 0 0 25 0 10 0 76214371 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4095642770 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 18 0 10 0 76214373 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76214373 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76214373 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76214376 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=892712 CPUtime=0.83
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10282 0 0 0 82 1 0 0 25 0 10 0 76214376 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=892712 CPUtime=0.92
/proc/10375/task/10382/stat : 10382 (java) R 10362 10361 29493 34817 29493 4202560 6293 0 0 0 88 4 0 0 19 0 10 0 76214376 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4148895674 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76214376 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=892712 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76214376 914137088 45141 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 897856

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

[startup+3.20849 s]
/proc/loadavg: 1.74 1.62 1.62 4/46 10384
/proc/meminfo: memFree=166852/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=886932 CPUtime=3.17
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 52799 0 1 0 295 22 0 0 25 0 10 0 76214370 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 221733 44347 1742 10 0 218824 0
[pid=10375/tid=10376] ppid=10362 vsize=886932 CPUtime=1.32
/proc/10375/task/10376/stat : 10376 (java) R 10362 10361 29493 34817 29493 4202560 35113 0 1 0 117 15 0 0 25 0 10 0 76214371 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4095993751 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 18 0 10 0 76214373 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 76214373 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 76214373 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76214376 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=886932 CPUtime=0.83
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10282 0 0 0 82 1 0 0 25 0 10 0 76214376 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=886932 CPUtime=1
/proc/10375/task/10382/stat : 10382 (java) S 10362 10361 29493 34817 29493 4202560 6325 0 0 0 96 4 0 0 20 0 10 0 76214376 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76214376 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=886932 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76214376 908218368 44347 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.2
Current children cumulated vsize (KiB) 892076

[startup+4.80894 s]
/proc/loadavg: 1.76 1.63 1.63 3/46 10384
/proc/meminfo: memFree=5972/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=888508 CPUtime=4.75
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 83287 0 1 0 442 33 0 0 25 0 10 0 76214370 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 222127 74309 1763 10 0 219218 0
[pid=10375/tid=10376] ppid=10362 vsize=888508 CPUtime=1.79
/proc/10375/task/10376/stat : 10376 (java) R 10362 10361 29493 34817 29493 4202560 50185 0 1 0 160 19 0 0 25 0 10 0 76214371 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4150288463 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=888508 CPUtime=0.34
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 14440 0 0 0 28 6 0 0 18 0 10 0 76214373 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=888508 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 76214373 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=888508 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 76214373 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=888508 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76214376 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=888508 CPUtime=1.28
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10338 0 0 0 127 1 0 0 15 0 10 0 76214376 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=888508 CPUtime=1.31
/proc/10375/task/10382/stat : 10382 (java) R 10362 10361 29493 34817 29493 4202560 7250 0 0 0 126 5 0 0 19 0 10 0 76214376 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4151794581 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=888508 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76214376 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=888508 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76214376 909832192 74309 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.78
Current children cumulated vsize (KiB) 893652

[startup+5.20599 s]
/proc/loadavg: 1.76 1.63 1.63 3/46 10384
/proc/meminfo: memFree=5972/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=887256 CPUtime=4.92
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 83723 0 1 0 459 33 0 0 25 0 11 0 76214370 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 221814 73955 1763 10 0 218905 0
[pid=10375/tid=10376] ppid=10362 vsize=887256 CPUtime=1.84
/proc/10375/task/10376/stat : 10376 (java) S 10362 10361 29493 34817 29493 4202560 50198 0 1 0 165 19 0 0 25 0 11 0 76214371 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=887256 CPUtime=0.34
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 14440 0 0 0 28 6 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=887256 CPUtime=1.28
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10338 0 0 0 127 1 0 0 15 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=887256 CPUtime=1.43
/proc/10375/task/10382/stat : 10382 (java) S 10362 10361 29493 34817 29493 4202560 7669 0 0 0 138 5 0 0 19 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.95
Current children cumulated vsize (KiB) 892400

[startup+5.40598 s]
/proc/loadavg: 1.76 1.63 1.63 3/46 10384
/proc/meminfo: memFree=5972/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=887256 CPUtime=4.92
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 83723 0 1 0 459 33 0 0 25 0 11 0 76214370 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 221814 73955 1763 10 0 218905 0
[pid=10375/tid=10376] ppid=10362 vsize=887256 CPUtime=1.84
/proc/10375/task/10376/stat : 10376 (java) S 10362 10361 29493 34817 29493 4202560 50198 0 1 0 165 19 0 0 25 0 11 0 76214371 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=887256 CPUtime=0.34
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 14440 0 0 0 28 6 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 76214373 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=887256 CPUtime=1.28
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10338 0 0 0 127 1 0 0 15 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=887256 CPUtime=1.43
/proc/10375/task/10382/stat : 10382 (java) S 10362 10361 29493 34817 29493 4202560 7669 0 0 0 138 5 0 0 19 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 76214376 908550144 73955 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.95
Current children cumulated vsize (KiB) 892400

[startup+5.50601 s]
/proc/loadavg: 1.76 1.63 1.63 1/46 10385
/proc/meminfo: memFree=6716/1048576 swapFree=0/0
[pid=10361] ppid=10360 vsize=2572 CPUtime=0
/proc/10361/stat : 10361 (p2cudf-trendy-1) S 10360 10361 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 76214367 2633728 271 1283457024 134512640 135304128 4288558112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10361/statm: 643 271 230 194 0 30 0
[pid=10362] ppid=10361 vsize=2572 CPUtime=0.03
/proc/10362/stat : 10362 (p2cudf) S 10361 10361 29493 34817 29493 4202496 392 3018 0 1 0 0 0 3 25 0 1 0 76214367 2633728 280 1283457024 134512640 135304128 4288199600 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/10362/statm: 643 280 239 194 0 30 0
[pid=10375] ppid=10362 vsize=887256 CPUtime=4.92
/proc/10375/stat : 10375 (java) S 10362 10361 29493 34817 29493 4202496 83725 0 1 0 459 33 0 0 25 0 10 0 76214370 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/10375/statm: 221814 73957 1765 10 0 218905 0
[pid=10375/tid=10376] ppid=10362 vsize=887256 CPUtime=1.84
/proc/10375/task/10376/stat : 10376 (java) S 10362 10361 29493 34817 29493 4202560 50198 0 1 0 165 19 0 0 20 0 10 0 76214371 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10377] ppid=10362 vsize=887256 CPUtime=0.34
/proc/10375/task/10377/stat : 10377 (java) S 10362 10361 29493 34817 29493 4202560 14441 0 0 0 28 6 0 0 16 0 10 0 76214373 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10378] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10378/stat : 10378 (java) S 10362 10361 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 76214373 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10379] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10379/stat : 10379 (java) S 10362 10361 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 76214373 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10380] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10380/stat : 10380 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 76214376 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10381] ppid=10362 vsize=887256 CPUtime=1.28
/proc/10375/task/10381/stat : 10381 (java) S 10362 10361 29493 34817 29493 4202560 10338 0 0 0 127 1 0 0 15 0 10 0 76214376 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10382] ppid=10362 vsize=887256 CPUtime=1.43
/proc/10375/task/10382/stat : 10382 (java) S 10362 10361 29493 34817 29493 4202560 7669 0 0 0 138 5 0 0 19 0 10 0 76214376 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10383] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10383/stat : 10383 (java) S 10362 10361 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 76214376 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
[pid=10375/tid=10384] ppid=10362 vsize=887256 CPUtime=0
/proc/10375/task/10384/stat : 10384 (java) S 10362 10361 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 76214376 908550144 73957 1283457024 134512640 134550932 4291986928 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.95
Current children cumulated vsize (KiB) 892400

Child status: 0
Real time (s): 5.55706
CPU time (s): 4.99631
CPU user time (s): 4.59629
CPU system time (s): 0.400025
CPU usage (%): 89.9093
Max. virtual memory (cumulated for all children) (KiB): 897856

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 4.59629
system time used= 0.400025
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 87535
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= 449
involuntary context switches= 460

runsolver used 0 second user time and 0 second system time

The end