runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/8ad21cec-4b22-11df-b280-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/debian-dudf//8ad21cec-4b22-11df-b280-00163e7a6f5e.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/8ad21cec-4b22-11df-b280-00163e7a6f5e.cudf.debian-dudf.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.08 1.02 1.01 1/44 927
/proc/meminfo: memFree=766152/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=3020 CPUtime=0
/proc/927/stat : 927 (runsolver) D 926 927 4159 34819 4229 4202560 72 0 0 0 0 0 0 0 23 0 1 0 884710851 3092480 93 1283457024 134512640 134586868 4293226336 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/927/statm: 755 93 62 19 0 53 0

[startup+0.156469 s]
/proc/loadavg: 1.08 1.02 1.01 1/44 927
/proc/meminfo: memFree=766152/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2576

[startup+0.204488 s]
/proc/loadavg: 1.08 1.02 1.01 1/44 927
/proc/meminfo: memFree=766152/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2576

[startup+0.304498 s]
/proc/loadavg: 1.08 1.02 1.01 1/44 927
/proc/meminfo: memFree=766152/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2576

[startup+0.704531 s]
/proc/loadavg: 1.08 1.02 1.01 1/44 927
/proc/meminfo: memFree=766152/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2576

[startup+1.50456 s]
/proc/loadavg: 1.08 1.02 1.01 1/48 942
/proc/meminfo: memFree=742004/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=857300 CPUtime=0.13
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 4428 0 150 0 13 0 0 0 18 0 10 0 884710920 877875200 4278 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 214325 4278 1702 10 0 211415 0
[pid=941/tid=942] ppid=929 vsize=857300 CPUtime=0.08
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 2560 0 60 0 8 0 0 0 20 0 10 0 884710947 877875200 4278 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.13
Current children cumulated vsize (KiB) 862452

[startup+3.10926 s]
/proc/loadavg: 1.08 1.02 1.01 4/56 950
/proc/meminfo: memFree=713144/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871480 CPUtime=1.5
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 31189 0 152 0 134 16 0 0 18 0 10 0 884710920 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217870 27243 1742 10 0 214960 0
[pid=941/tid=942] ppid=929 vsize=871480 CPUtime=0.62
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 21873 0 60 0 52 10 0 0 19 0 10 0 884710947 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4095224588 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 15 0 10 0 884710962 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 8 0 1 0 0 0 0 0 19 0 10 0 884710963 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 20 0 10 0 884710963 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 10 0 884710974 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871480 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 10 0 884710974 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871480 CPUtime=0.73
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 7693 0 2 0 67 6 0 0 15 0 10 0 884710974 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 10 0 884710974 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871480 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 884710974 892395520 27243 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 876632
heavy processes:

[startup+6.30622 s]
/proc/loadavg: 1.07 1.02 1.00 4/56 950
/proc/meminfo: memFree=470736/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871608 CPUtime=4.6
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 88600 0 153 0 426 34 0 0 18 0 10 0 884710920 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217902 81459 1766 10 0 214992 0
[pid=941/tid=942] ppid=929 vsize=871608 CPUtime=2.06
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50295 0 60 0 189 17 0 0 22 0 10 0 884710947 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871608 CPUtime=0.57
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 25658 0 0 0 48 9 0 0 16 0 10 0 884710962 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871608 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 10 0 884710963 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871608 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 884710963 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871608 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 10 0 884710974 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871608 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 10 0 884710974 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871608 CPUtime=1.82
/proc/941/task/948/stat : 948 (java) R 929 927 4159 34819 4229 4202560 11026 0 3 0 174 8 0 0 16 0 10 0 884710974 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4152164613 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871608 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 10 0 884710974 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871608 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 884710974 892526592 81459 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 4.6
Current children cumulated vsize (KiB) 876760
heavy processes:

[startup+12.7073 s]
/proc/loadavg: 1.07 1.02 1.00 2/57 952
/proc/meminfo: memFree=399384/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=870524 CPUtime=10.93
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 95631 0 153 0 1057 36 0 0 18 0 11 0 884710920 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217631 87306 1793 10 0 214721 0
[pid=941/tid=942] ppid=929 vsize=870524 CPUtime=6.76
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50360 0 60 0 659 17 0 0 25 0 11 0 884710947 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4096165737 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=870524 CPUtime=0.81
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 31618 0 0 0 70 11 0 0 15 0 11 0 884710962 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 884710963 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 884710963 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=870524 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=870524 CPUtime=3.21
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12028 0 3 0 313 8 0 0 15 0 11 0 884710974 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=952] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/952/stat : 952 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884711911 891416576 87306 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.93
Current children cumulated vsize (KiB) 875676

[startup+25.5103 s]
/proc/loadavg: 1.05 1.01 1.00 2/57 958
/proc/meminfo: memFree=364044/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=870524 CPUtime=23.73
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104431 0 153 0 2335 38 0 0 18 0 11 0 884710920 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217631 96106 1794 10 0 214721 0
[pid=941/tid=942] ppid=929 vsize=870524 CPUtime=18.85
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50361 0 60 0 1868 17 0 0 25 0 11 0 884710947 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4096343520 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=870524 CPUtime=1.31
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40410 0 0 0 118 13 0 0 16 0 11 0 884710962 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 18 0 11 0 884710963 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 884710963 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=870524 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=870524 CPUtime=3.42
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 334 8 0 0 16 0 11 0 884710974 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/950/stat : 950 (java) R 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=958] ppid=929 vsize=870524 CPUtime=0
/proc/941/task/958/stat : 958 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884713327 891416576 96106 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 23.73
Current children cumulated vsize (KiB) 875676

[startup+51.1074 s]
/proc/loadavg: 1.11 1.03 1.01 2/57 975
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=49.31
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104988 0 153 0 4893 38 0 0 18 0 11 0 884710920 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96663 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=42.8
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50364 0 60 0 4263 17 0 0 25 0 11 0 884710947 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4096166103 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=2.69
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 256 13 0 0 15 0 11 0 884710962 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.66
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 358 8 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=975] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/975/stat : 975 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884715094 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 49.31
Current children cumulated vsize (KiB) 876324

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

[startup+54.2084 s]
/proc/loadavg: 1.11 1.03 1.01 2/57 975
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=52.41
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104988 0 153 0 5203 38 0 0 18 0 11 0 884710920 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96663 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=45.9
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50364 0 60 0 4573 17 0 0 25 0 11 0 884710947 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4096242771 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=2.69
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 256 13 0 0 15 0 11 0 884710962 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.66
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 358 8 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=975] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/975/stat : 975 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884715094 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 52.41
Current children cumulated vsize (KiB) 876324

[startup+57.4093 s]
/proc/loadavg: 1.10 1.03 1.01 2/57 975
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=55.61
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104988 0 153 0 5523 38 0 0 18 0 11 0 884710920 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96663 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=49.1
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50364 0 60 0 4893 17 0 0 25 0 11 0 884710947 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4096166103 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=2.69
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 256 13 0 0 15 0 11 0 884710962 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.66
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 358 8 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=975] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/975/stat : 975 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884715094 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 55.61
Current children cumulated vsize (KiB) 876324

[startup+58.2096 s]
/proc/loadavg: 1.10 1.03 1.01 2/57 978
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=56.41
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104988 0 153 0 5603 38 0 0 18 0 11 0 884710920 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96663 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=49.86
/proc/941/task/942/stat : 942 (java) R 929 927 4159 34819 4229 4202560 50364 0 60 0 4969 17 0 0 25 0 11 0 884710947 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4096265819 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=2.69
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 256 13 0 0 16 0 11 0 884710962 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.7
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 362 8 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) R 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=978] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/978/stat : 978 (java) S 929 927 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 11 0 884716655 892080128 96663 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 56.41
Current children cumulated vsize (KiB) 876324

[startup+59.0042 s]
/proc/loadavg: 1.10 1.03 1.01 2/57 978
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=57.02
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104994 0 153 0 5664 38 0 0 18 0 11 0 884710920 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96669 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=50.02
/proc/941/task/942/stat : 942 (java) S 929 927 4159 34819 4229 4202560 50370 0 60 0 4985 17 0 0 25 0 11 0 884710947 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=3.11
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 298 13 0 0 16 0 11 0 884710962 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.73
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 365 8 0 0 16 0 11 0 884710974 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96669 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57.02
Current children cumulated vsize (KiB) 876324

[startup+59.4122 s]
/proc/loadavg: 1.10 1.03 1.01 1/57 979
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=57.1
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104996 0 153 0 5670 40 0 0 18 0 11 0 884710920 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96671 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=50.02
/proc/941/task/942/stat : 942 (java) S 929 927 4159 34819 4229 4202560 50370 0 60 0 4985 17 0 0 25 0 11 0 884710947 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=3.11
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 298 13 0 0 16 0 11 0 884710962 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.74
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 366 8 0 0 15 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) S 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=979] ppid=929 vsize=871172 CPUtime=0.05
/proc/941/task/979/stat : 979 (java) R 929 927 4159 34819 4229 4202560 2 0 0 0 4 1 0 0 20 0 11 0 884716735 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4094907494 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57.1
Current children cumulated vsize (KiB) 876324

[startup+59.5122 s]
/proc/loadavg: 1.10 1.03 1.01 1/57 979
/proc/meminfo: memFree=361688/1048576 swapFree=2077616/2097144
[pid=927] ppid=926 vsize=2576 CPUtime=0
/proc/927/stat : 927 (p2cudf-trendy-1) S 926 927 4159 34819 4229 4202496 378 0 0 0 0 0 0 0 24 0 1 0 884710851 2637824 274 1283457024 134512640 135304128 4287923008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/927/statm: 644 274 233 194 0 30 0
[pid=929] ppid=927 vsize=2576 CPUtime=0
/proc/929/stat : 929 (p2cudf) S 927 927 4159 34819 4229 4202496 394 2790 0 128 0 0 0 0 19 0 1 0 884710852 2637824 282 1283457024 134512640 135304128 4287548112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/929/statm: 644 282 241 194 0 30 0
[pid=941] ppid=929 vsize=871172 CPUtime=57.19
/proc/941/stat : 941 (java) S 929 927 4159 34819 4229 4202496 104996 0 153 0 5677 42 0 0 18 0 11 0 884710920 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/941/statm: 217793 96671 1794 10 0 214883 0
[pid=941/tid=942] ppid=929 vsize=871172 CPUtime=50.02
/proc/941/task/942/stat : 942 (java) S 929 927 4159 34819 4229 4202560 50370 0 60 0 4985 17 0 0 25 0 11 0 884710947 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=943] ppid=929 vsize=871172 CPUtime=3.11
/proc/941/task/943/stat : 943 (java) S 929 927 4159 34819 4229 4202560 40956 0 0 0 298 13 0 0 16 0 11 0 884710962 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 0 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=944] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/944/stat : 944 (java) S 929 927 4159 34819 4229 4202560 9 0 1 0 0 0 0 0 15 0 11 0 884710963 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=945] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/945/stat : 945 (java) S 929 927 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 15 0 11 0 884710963 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=946] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/946/stat : 946 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 20 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=947] ppid=929 vsize=871172 CPUtime=0.14
/proc/941/task/947/stat : 947 (java) S 929 927 4159 34819 4229 4202560 593 0 6 0 14 0 0 0 16 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=948] ppid=929 vsize=871172 CPUtime=3.78
/proc/941/task/948/stat : 948 (java) S 929 927 4159 34819 4229 4202560 12035 0 3 0 370 8 0 0 15 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=949] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/949/stat : 949 (java) S 929 927 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 21 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 18446612133393160064 0 0 -1 0 0 0 0
[pid=941/tid=950] ppid=929 vsize=871172 CPUtime=0
/proc/941/task/950/stat : 950 (java) R 929 927 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 884710974 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=941/tid=979] ppid=929 vsize=871172 CPUtime=0.11
/proc/941/task/979/stat : 979 (java) R 929 927 4159 34819 4229 4202560 2 0 0 0 8 3 0 0 21 0 11 0 884716735 892080128 96671 1283457024 134512640 134550932 4294503536 18446744073709551615 4094891232 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 57.19
Current children cumulated vsize (KiB) 876324

Child status: 0
Real time (s): 59.5985
CPU time (s): 57.2476
CPU user time (s): 56.7956
CPU system time (s): 0.452028
CPU usage (%): 96.0554
Max. virtual memory (cumulated for all children) (KiB): 881736

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 56.7955
system time used= 0.452028
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 108579
page faults= 281
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 3604
involuntary context switches= 3233

runsolver used 0 second user time and 0.008 second system time

The end