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/7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf.debian.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/debian//7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/7266f636-4b23-11df-9e6e-00163e7a6f5e.cudf.debian.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.25 1.11 1.07 5/37 9922
/proc/meminfo: memFree=578000/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2568 CPUtime=0
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 353 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/9920/statm: 642 271 231 194 0 29 0
[pid=9921] ppid=9920 vsize=2568 CPUtime=0
/proc/9921/stat : 9921 (p2cudf) R 9920 9919 29493 34817 29493 4202560 94 0 0 0 0 0 0 0 25 0 1 0 75292616 2629632 119 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/9921/statm: 642 119 77 194 0 29 0
[pid=9922] ppid=9921 vsize=2568 CPUtime=0
/proc/9922/stat : 9922 (p2cudf) R 9921 9919 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75292616 2629632 42 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/9922/statm: 642 42 0 194 0 29 0

[startup+0.183757 s]
/proc/loadavg: 1.25 1.11 1.07 5/37 9922
/proc/meminfo: memFree=578000/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5140

[startup+0.213754 s]
/proc/loadavg: 1.25 1.11 1.07 5/37 9922
/proc/meminfo: memFree=578000/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5140

[startup+0.303776 s]
/proc/loadavg: 1.25 1.11 1.07 5/37 9922
/proc/meminfo: memFree=578000/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5140

[startup+0.703917 s]
/proc/loadavg: 1.25 1.11 1.07 5/37 9922
/proc/meminfo: memFree=578000/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5140

[startup+1.5041 s]
/proc/loadavg: 1.39 1.15 1.08 3/46 9942
/proc/meminfo: memFree=527396/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=885064 CPUtime=1.46
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 20566 0 1 0 139 7 0 0 25 0 10 0 75292619 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 221266 16886 1730 10 0 218357 0
[pid=9933/tid=9934] ppid=9920 vsize=885064 CPUtime=0.81
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 8766 0 1 0 77 4 0 0 25 0 10 0 75292620 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4149212394 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 16 0 10 0 75292622 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75292622 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75292622 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=885064 CPUtime=0.44
/proc/9933/task/9939/stat : 9939 (java) R 9920 9919 29493 34817 29493 4202560 8696 0 0 0 41 3 0 0 25 0 10 0 75292625 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=885064 CPUtime=0.2
/proc/9933/task/9940/stat : 9940 (java) R 9920 9919 29493 34817 29493 4202560 2025 0 0 0 20 0 0 0 18 0 10 0 75292625 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4152054955 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=885064 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75292625 906305536 16886 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 890204

[startup+3.10443 s]
/proc/loadavg: 1.39 1.15 1.08 2/46 9942
/proc/meminfo: memFree=437620/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=883036 CPUtime=3.05
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 75745 0 1 0 282 23 0 0 25 0 10 0 75292619 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220759 71098 1738 10 0 217850 0
[pid=9933/tid=9934] ppid=9920 vsize=883036 CPUtime=1.83
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50020 0 1 0 170 13 0 0 25 0 10 0 75292620 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4095390741 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=883036 CPUtime=0.27
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 13011 0 0 0 22 5 0 0 16 0 10 0 75292622 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=883036 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 75292622 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=883036 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75292622 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=883036 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=883036 CPUtime=0.5
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 8739 0 0 0 47 3 0 0 15 0 10 0 75292625 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=883036 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 10 0 75292625 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=883036 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=883036 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75292625 904228864 71098 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.07
Current children cumulated vsize (KiB) 888176

[startup+6.30525 s]
/proc/loadavg: 1.39 1.15 1.08 2/46 9942
/proc/meminfo: memFree=247280/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=882932 CPUtime=6.25
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 96949 0 1 0 593 32 0 0 25 0 10 0 75292619 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220733 90452 1764 10 0 217824 0
[pid=9933/tid=9934] ppid=9920 vsize=882932 CPUtime=3.08
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50235 0 1 0 294 14 0 0 25 0 10 0 75292620 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4095124578 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=882932 CPUtime=0.82
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 31927 0 0 0 70 12 0 0 16 0 10 0 75292622 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=882932 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 75292622 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=882932 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75292622 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=882932 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=882932 CPUtime=1.9
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10812 0 0 0 185 5 0 0 15 0 10 0 75292625 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=882932 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 10 0 75292625 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=882932 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75292625 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=882932 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75292625 904122368 90452 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.27
Current children cumulated vsize (KiB) 888072

[startup+12.7069 s]
/proc/loadavg: 1.41 1.16 1.08 2/47 9943
/proc/meminfo: memFree=221612/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=12.66
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 97070 0 1 0 1232 34 0 0 25 0 11 0 75292619 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 90221 1780 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=8.79
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50262 0 1 0 864 15 0 0 25 0 11 0 75292620 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4095270748 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=0.82
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 31927 0 0 0 70 12 0 0 16 0 11 0 75292622 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=2.59
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10902 0 0 0 254 5 0 0 15 0 11 0 75292625 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 90221 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.68
Current children cumulated vsize (KiB) 886988

[startup+25.5107 s]
/proc/loadavg: 1.35 1.15 1.08 2/47 9943
/proc/meminfo: memFree=222480/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=25.44
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 97078 0 1 0 2510 34 0 0 25 0 11 0 75292619 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 90229 1782 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=21.37
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50263 0 1 0 2122 15 0 0 25 0 11 0 75292620 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4096281913 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=0.82
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 31927 0 0 0 70 12 0 0 15 0 11 0 75292622 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=2.79
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10909 0 0 0 274 5 0 0 15 0 11 0 75292625 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 90229 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.46
Current children cumulated vsize (KiB) 886988

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

[startup+38.2039 s]
/proc/loadavg: 1.27 1.14 1.08 2/47 9943
/proc/meminfo: memFree=186644/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=38.11
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106121 0 1 0 3775 36 0 0 25 0 11 0 75292619 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 99272 1788 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=33.13
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50264 0 1 0 3298 15 0 0 25 0 11 0 75292620 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4096287859 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=1.33
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 40919 0 0 0 119 14 0 0 15 0 11 0 75292622 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=3.2
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 315 5 0 0 15 0 11 0 75292625 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 99272 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 38.13
Current children cumulated vsize (KiB) 886988

[startup+44.6059 s]
/proc/loadavg: 1.25 1.14 1.08 2/46 9943
/proc/meminfo: memFree=185536/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=44.5
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106424 0 1 0 4414 36 0 0 25 0 11 0 75292619 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 99575 1788 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=39.08
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50264 0 1 0 3893 15 0 0 25 0 11 0 75292620 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4096295184 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=1.76
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 41222 0 0 0 162 14 0 0 15 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=3.2
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 315 5 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 44.52
Current children cumulated vsize (KiB) 886988

[startup+46.2063 s]
/proc/loadavg: 1.23 1.14 1.08 2/46 9943
/proc/meminfo: memFree=185536/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=46.09
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106424 0 1 0 4573 36 0 0 25 0 11 0 75292619 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 99575 1788 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=40.68
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50264 0 1 0 4053 15 0 0 25 0 11 0 75292620 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4096287859 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=1.76
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 41222 0 0 0 162 14 0 0 15 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=3.2
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 315 5 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 46.11
Current children cumulated vsize (KiB) 886988

[startup+47.0066 s]
/proc/loadavg: 1.23 1.14 1.08 2/46 9943
/proc/meminfo: memFree=185536/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=881848 CPUtime=46.89
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106424 0 1 0 4653 36 0 0 25 0 11 0 75292619 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220462 99575 1788 10 0 217553 0
[pid=9933/tid=9934] ppid=9920 vsize=881848 CPUtime=41.44
/proc/9933/task/9934/stat : 9934 (java) R 9920 9919 29493 34817 29493 4202560 50264 0 1 0 4129 15 0 0 25 0 11 0 75292620 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4096295165 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=881848 CPUtime=1.76
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 41222 0 0 0 162 14 0 0 15 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=881848 CPUtime=3.23
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 318 5 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=881848 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=881848 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903012352 99575 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 46.91
Current children cumulated vsize (KiB) 886988

[startup+47.8107 s]
/proc/loadavg: 1.23 1.14 1.08 2/46 9943
/proc/meminfo: memFree=185536/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=882172 CPUtime=47.18
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106437 0 1 0 4681 37 0 0 25 0 12 0 75292619 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220543 99588 1788 10 0 217634 0
[pid=9933/tid=9934] ppid=9920 vsize=882172 CPUtime=41.68
/proc/9933/task/9934/stat : 9934 (java) S 9920 9919 29493 34817 29493 4202560 50272 0 1 0 4153 15 0 0 25 0 12 0 75292620 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=882172 CPUtime=1.76
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 41222 0 0 0 162 14 0 0 16 0 12 0 75292622 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75292622 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75292622 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75292625 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=882172 CPUtime=3.27
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 322 5 0 0 16 0 12 0 75292625 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=882172 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 12 0 75292625 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75292625 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 75292625 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75293299 903344128 99588 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 47.2
Current children cumulated vsize (KiB) 887312

[startup+48.0068 s]
/proc/loadavg: 1.23 1.14 1.08 2/46 9943
/proc/meminfo: memFree=185536/1048576 swapFree=0/0
[pid=9919] ppid=9918 vsize=2568 CPUtime=0
/proc/9919/stat : 9919 (p2cudf-trendy-1) S 9918 9919 29493 34817 29493 4202496 375 0 0 0 0 0 0 0 18 0 1 0 75292616 2629632 271 1283457024 134512640 135304128 4290266480 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9919/statm: 642 271 230 194 0 29 0
[pid=9920] ppid=9919 vsize=2572 CPUtime=0.02
/proc/9920/stat : 9920 (p2cudf) S 9919 9919 29493 34817 29493 4202496 390 3013 0 1 0 0 0 2 25 0 1 0 75292616 2633728 280 1283457024 134512640 135304128 4292956160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9920/statm: 643 280 239 194 0 30 0
[pid=9933] ppid=9920 vsize=882172 CPUtime=47.34
/proc/9933/stat : 9933 (java) S 9920 9919 29493 34817 29493 4202496 106439 0 1 0 4692 42 0 0 25 0 11 0 75292619 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9933/statm: 220543 99590 1789 10 0 217634 0
[pid=9933/tid=9934] ppid=9920 vsize=882172 CPUtime=41.68
/proc/9933/task/9934/stat : 9934 (java) S 9920 9919 29493 34817 29493 4202560 50272 0 1 0 4153 15 0 0 19 0 11 0 75292620 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9935] ppid=9920 vsize=882172 CPUtime=1.76
/proc/9933/task/9935/stat : 9935 (java) S 9920 9919 29493 34817 29493 4202560 41222 0 0 0 162 14 0 0 16 0 11 0 75292622 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 0 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9936] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9936/stat : 9936 (java) S 9920 9919 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75292622 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9937] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9937/stat : 9937 (java) S 9920 9919 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75292622 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9938] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9938/stat : 9938 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9939] ppid=9920 vsize=882172 CPUtime=3.32
/proc/9933/task/9939/stat : 9939 (java) S 9920 9919 29493 34817 29493 4202560 10959 0 0 0 327 5 0 0 15 0 11 0 75292625 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9940] ppid=9920 vsize=882172 CPUtime=0.43
/proc/9933/task/9940/stat : 9940 (java) S 9920 9919 29493 34817 29493 4202560 2901 0 0 0 42 1 0 0 16 0 11 0 75292625 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9941] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9941/stat : 9941 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75292625 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9942] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9942/stat : 9942 (java) S 9920 9919 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75292625 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
[pid=9933/tid=9943] ppid=9920 vsize=882172 CPUtime=0
/proc/9933/task/9943/stat : 9943 (java) S 9920 9919 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75293299 903344128 99590 1283457024 134512640 134550932 4294109104 18446744073709551615 4294960130 0 4 0 16800975 18446612133396264960 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 47.36
Current children cumulated vsize (KiB) 887312

Child status: 0
Real time (s): 48.058
CPU time (s): 47.411
CPU user time (s): 46.9309
CPU system time (s): 0.48003
CPU usage (%): 98.6535
Max. virtual memory (cumulated for all children) (KiB): 891928

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 46.9309
system time used= 0.48003
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 110239
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= 1857
involuntary context switches= 2295

runsolver used 0.008 second user time and 0 second system time

The end