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/201012081559/p2cudf-user-1.10/9705aeb6-0b30-11df-9f23-00163e1d94dc.cudf.upgrades.log.runsolver ./p2cudf-user-1.10 /home/misc2010/data/upgrades//9705aeb6-0b30-11df-9f23-00163e1d94dc.cudf /home/misc2010/tmp/201012081559/p2cudf-user-1.10/9705aeb6-0b30-11df-9f23-00163e1d94dc.cudf.upgrades.result -notuptodate,-removed,-changed 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 2.71 1.31 0.51 5/47 1308
/proc/meminfo: memFree=150408/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2568 CPUtime=0
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 354 0 0 0 0 0 0 0 24 0 1 0 891711017 2629632 273 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/1306/statm: 642 273 234 194 0 28 0
[pid=1307] ppid=1306 vsize=2568 CPUtime=0
/proc/1307/stat : 1307 (p2cudf) R 1306 1305 28116 34820 28116 4202560 92 0 0 0 0 0 0 0 25 0 1 0 891711017 2629632 116 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/1307/statm: 642 116 75 194 0 28 0
[pid=1308] ppid=1307 vsize=2568 CPUtime=0
/proc/1308/stat : 1308 (p2cudf) R 1307 1305 28116 34820 28116 4202560 0 0 0 0 0 0 0 0 25 0 1 0 891711017 2629632 41 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0
/proc/1308/statm: 642 41 0 194 0 28 0

[startup+0.136913 s]
/proc/loadavg: 2.71 1.31 0.51 5/47 1308
/proc/meminfo: memFree=150408/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.206931 s]
/proc/loadavg: 2.71 1.31 0.51 5/47 1308
/proc/meminfo: memFree=150408/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.306953 s]
/proc/loadavg: 2.71 1.31 0.51 5/47 1308
/proc/meminfo: memFree=150408/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+0.707029 s]
/proc/loadavg: 2.71 1.31 0.51 5/47 1308
/proc/meminfo: memFree=150408/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5144

[startup+1.50725 s]
/proc/loadavg: 2.71 1.31 0.51 4/56 1328
/proc/meminfo: memFree=109680/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
[pid=1319] ppid=1306 vsize=869080 CPUtime=1.47
/proc/1319/stat : 1319 (java) S 1306 1305 28116 34820 28116 4202496 17956 0 1 0 137 10 0 0 25 0 10 0 891711020 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1319/statm: 217270 16305 1742 10 0 214360 0
[pid=1319/tid=1320] ppid=1306 vsize=869080 CPUtime=0.79
/proc/1319/task/1320/stat : 1320 (java) R 1306 1305 28116 34820 28116 4202560 11892 0 1 0 73 6 0 0 25 0 10 0 891711021 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4158905637 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1319/tid=1321] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1321/stat : 1321 (java) S 1306 1305 28116 34820 28116 4202560 5 0 0 0 0 0 0 0 17 0 10 0 891711023 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1322] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1322/stat : 1322 (java) S 1306 1305 28116 34820 28116 4202560 9 0 0 0 0 0 0 0 25 0 10 0 891711023 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1323] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1323/stat : 1323 (java) S 1306 1305 28116 34820 28116 4202560 4 0 0 0 0 0 0 0 25 0 10 0 891711023 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1324] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1324/stat : 1324 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 25 0 10 0 891711026 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1325] ppid=1306 vsize=869080 CPUtime=0.48
/proc/1319/task/1325/stat : 1325 (java) R 1306 1305 28116 34820 28116 4202560 4862 0 0 0 44 4 0 0 23 0 10 0 891711026 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4149921413 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1319/tid=1326] ppid=1306 vsize=869080 CPUtime=0.2
/proc/1319/task/1326/stat : 1326 (java) S 1306 1305 28116 34820 28116 4202560 90 0 0 0 20 0 0 0 23 0 10 0 891711026 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1327] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1327/stat : 1327 (java) S 1306 1305 28116 34820 28116 4202560 0 0 0 0 0 0 0 0 25 0 10 0 891711026 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1328] ppid=1306 vsize=869080 CPUtime=0
/proc/1319/task/1328/stat : 1328 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 15 0 10 0 891711026 889937920 16305 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 874224

[startup+3.10402 s]
/proc/loadavg: 2.73 1.34 0.52 4/56 1328
/proc/meminfo: memFree=72100/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
[pid=1319] ppid=1306 vsize=877760 CPUtime=3.06
/proc/1319/stat : 1319 (java) S 1306 1305 28116 34820 28116 4202496 29296 0 1 0 292 14 0 0 25 0 11 0 891711020 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1319/statm: 219440 25383 1763 10 0 216530 0
[pid=1319/tid=1320] ppid=1306 vsize=877760 CPUtime=1.31
/proc/1319/task/1320/stat : 1320 (java) S 1306 1305 28116 34820 28116 4202560 18826 0 1 0 122 9 0 0 25 0 11 0 891711021 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1321] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1321/stat : 1321 (java) S 1306 1305 28116 34820 28116 4202560 5 0 0 0 0 0 0 0 15 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1322] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1322/stat : 1322 (java) S 1306 1305 28116 34820 28116 4202560 9 0 0 0 0 0 0 0 25 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1323] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1323/stat : 1323 (java) S 1306 1305 28116 34820 28116 4202560 4 0 0 0 0 0 0 0 25 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1324] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1324/stat : 1324 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1325] ppid=1306 vsize=877760 CPUtime=0.94
/proc/1319/task/1325/stat : 1325 (java) S 1306 1305 28116 34820 28116 4202560 8495 0 0 0 90 4 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1326] ppid=1306 vsize=877760 CPUtime=0.79
/proc/1319/task/1326/stat : 1326 (java) S 1306 1305 28116 34820 28116 4202560 859 0 0 0 79 0 0 0 19 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1327] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1327/stat : 1327 (java) S 1306 1305 28116 34820 28116 4202560 0 0 0 0 0 0 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1328] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1328/stat : 1328 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 15 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 882904

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

[startup+3.20402 s]
/proc/loadavg: 2.73 1.34 0.52 4/56 1328
/proc/meminfo: memFree=72100/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
[pid=1319] ppid=1306 vsize=877760 CPUtime=3.06
/proc/1319/stat : 1319 (java) S 1306 1305 28116 34820 28116 4202496 29296 0 1 0 292 14 0 0 25 0 11 0 891711020 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1319/statm: 219440 25383 1763 10 0 216530 0
[pid=1319/tid=1320] ppid=1306 vsize=877760 CPUtime=1.31
/proc/1319/task/1320/stat : 1320 (java) S 1306 1305 28116 34820 28116 4202560 18826 0 1 0 122 9 0 0 25 0 11 0 891711021 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1321] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1321/stat : 1321 (java) S 1306 1305 28116 34820 28116 4202560 5 0 0 0 0 0 0 0 15 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1322] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1322/stat : 1322 (java) S 1306 1305 28116 34820 28116 4202560 9 0 0 0 0 0 0 0 25 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1323] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1323/stat : 1323 (java) S 1306 1305 28116 34820 28116 4202560 4 0 0 0 0 0 0 0 25 0 11 0 891711023 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1324] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1324/stat : 1324 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1325] ppid=1306 vsize=877760 CPUtime=0.94
/proc/1319/task/1325/stat : 1325 (java) S 1306 1305 28116 34820 28116 4202560 8495 0 0 0 90 4 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1326] ppid=1306 vsize=877760 CPUtime=0.79
/proc/1319/task/1326/stat : 1326 (java) S 1306 1305 28116 34820 28116 4202560 859 0 0 0 79 0 0 0 19 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1327] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1327/stat : 1327 (java) S 1306 1305 28116 34820 28116 4202560 0 0 0 0 0 0 0 0 25 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1328] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1328/stat : 1328 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 15 0 11 0 891711026 898826240 25383 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 882904

[startup+3.60403 s]
/proc/loadavg: 2.73 1.34 0.52 1/57 1329
/proc/meminfo: memFree=51508/1048576 swapFree=2080404/2097144
[pid=1305] ppid=1304 vsize=2572 CPUtime=0
/proc/1305/stat : 1305 (p2cudf-user-1.1) S 1304 1305 28116 34820 28116 4202496 377 0 0 0 0 0 0 0 24 0 1 0 891711017 2633728 272 1283457024 134512640 135304128 4291526272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1305/statm: 643 272 233 194 0 29 0
[pid=1306] ppid=1305 vsize=2572 CPUtime=0.02
/proc/1306/stat : 1306 (p2cudf) S 1305 1305 28116 34820 28116 4202496 390 2991 0 1 0 0 0 2 25 0 1 0 891711017 2633728 281 1283457024 134512640 135304128 4293099568 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1306/statm: 643 281 241 194 0 29 0
[pid=1319] ppid=1306 vsize=877760 CPUtime=3.06
/proc/1319/stat : 1319 (java) S 1306 1305 28116 34820 28116 4202496 29297 0 1 0 292 14 0 0 25 0 10 0 891711020 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1319/statm: 219440 25384 1763 10 0 216530 0
[pid=1319/tid=1320] ppid=1306 vsize=877760 CPUtime=1.31
/proc/1319/task/1320/stat : 1320 (java) S 1306 1305 28116 34820 28116 4202560 18826 0 1 0 122 9 0 0 18 0 10 0 891711021 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1321] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1321/stat : 1321 (java) S 1306 1305 28116 34820 28116 4202560 5 0 0 0 0 0 0 0 15 0 10 0 891711023 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 0 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1322] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1322/stat : 1322 (java) S 1306 1305 28116 34820 28116 4202560 9 0 0 0 0 0 0 0 25 0 10 0 891711023 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1323] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1323/stat : 1323 (java) S 1306 1305 28116 34820 28116 4202560 4 0 0 0 0 0 0 0 25 0 10 0 891711023 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1324] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1324/stat : 1324 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 25 0 10 0 891711026 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1325] ppid=1306 vsize=877760 CPUtime=0.94
/proc/1319/task/1325/stat : 1325 (java) S 1306 1305 28116 34820 28116 4202560 8495 0 0 0 90 4 0 0 25 0 10 0 891711026 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1326] ppid=1306 vsize=877760 CPUtime=0.79
/proc/1319/task/1326/stat : 1326 (java) S 1306 1305 28116 34820 28116 4202560 859 0 0 0 79 0 0 0 19 0 10 0 891711026 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1327] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1327/stat : 1327 (java) S 1306 1305 28116 34820 28116 4202560 0 0 0 0 0 0 0 0 25 0 10 0 891711026 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
[pid=1319/tid=1328] ppid=1306 vsize=877760 CPUtime=0
/proc/1319/task/1328/stat : 1328 (java) S 1306 1305 28116 34820 28116 4202560 1 0 0 0 0 0 0 0 15 0 10 0 891711026 898826240 25384 1283457024 134512640 134550932 4291659120 18446744073709551615 4294960130 0 4 0 16800975 18446612132432356480 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.08
Current children cumulated vsize (KiB) 882904

Child status: 0
Real time (s): 3.65652
CPU time (s): 3.10019
CPU user time (s): 2.92818
CPU system time (s): 0.17201
CPU usage (%): 84.7853
Max. virtual memory (cumulated for all children) (KiB): 882904

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 2.92818
system time used= 0.17201
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 33075
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= 293
involuntary context switches= 289

runsolver used 0 second user time and 0 second system time

The end