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: runsolver -s SIGUSR1 -M 1124 -C 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/aspuncud-full-1.7/trendy-size/install/rand411.cudf.log.runsolver /home/competition/aspuncud-full-1.7/aspuncud-full /home/competition/data/install/rand411.cudf /tmp/misc2012/2012-09-02-22:42/full/aspuncud-full-1.7/trendy-size/install/rand411.cudf.result -count(removed),-sum(solution,installedsize),-notuptodate(solution),-unsat_recommends(solution),-count(new) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 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

[startup+0 s]
/proc/loadavg: 1.05 1.04 0.96 2/56 23766
/proc/meminfo: memFree=700980/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=3152 CPUtime=0
/proc/23766/stat : 23766 (runsolver) R 23765 32685 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 41413690 3227648 32 18446744073709551615 134512640 134586868 4291739200 4291737248 4152001584 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.123718 s]
/proc/loadavg: 1.05 1.04 0.96 2/56 23766
/proc/meminfo: memFree=700980/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 9212

[startup+0.200327 s]
/proc/loadavg: 1.05 1.04 0.96 2/56 23766
/proc/meminfo: memFree=700980/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 9212

[startup+0.300327 s]
/proc/loadavg: 1.05 1.04 0.96 2/56 23766
/proc/meminfo: memFree=700980/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 9212

[startup+0.700231 s]
/proc/loadavg: 1.05 1.04 0.96 2/56 23766
/proc/meminfo: memFree=700980/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 9212

[startup+1.50031 s]
/proc/loadavg: 1.05 1.04 0.96 2/58 23779
/proc/meminfo: memFree=666484/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23779] ppid=23766 vsize=50472 CPUtime=1.43
/proc/23779/stat : 23779 (cudf2lp) R 23766 23766 32685 0 -1 4202496 11495 0 0 0 137 6 0 0 20 0 1 0 41413695 51683328 9762 18446744073709551615 4194304 5690517 140735314927120 140735314923320 4363573 0 0 16781316 0 0 0 0 17 0 0 0 1 0 0
/proc/23779/statm: 12618 9762 160 366 0 12249 0
Current children cumulated CPU time (s) 1.46
Current children cumulated vsize (KiB) 59684

[startup+3.10033 s]
/proc/loadavg: 1.05 1.04 0.96 2/58 23779
/proc/meminfo: memFree=634616/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=0.03
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 695 2805 0 2 0 0 2 1 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23779] ppid=23766 vsize=93028 CPUtime=3.02
/proc/23779/stat : 23779 (cudf2lp) R 23766 23766 32685 0 -1 4202496 26506 0 0 0 288 14 0 0 20 0 1 0 41413695 95260672 19779 18446744073709551615 4194304 5690517 140735314927120 140735314924760 4956126 0 0 16781316 0 0 0 0 17 0 0 0 1 0 0
/proc/23779/statm: 23257 19779 160 366 0 22888 0
Current children cumulated CPU time (s) 3.05
Current children cumulated vsize (KiB) 102240

[startup+6.30032 s]
/proc/loadavg: 1.05 1.04 0.96 2/58 23780
/proc/meminfo: memFree=646148/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=5.26
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 723 42127 0 2 0 0 495 31 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23780] ppid=23766 vsize=28460 CPUtime=0.96
/proc/23780/stat : 23780 (gringo) R 23766 23766 32685 0 -1 4202496 7382 0 0 0 92 4 0 0 20 0 1 0 41414223 29143040 6271 18446744073709551615 4194304 6531320 140736881518736 140736881514552 5511058 0 0 16781316 16386 0 0 0 17 0 0 0 0 0 0
/proc/23780/statm: 7115 6271 259 571 0 6536 0
Current children cumulated CPU time (s) 6.22
Current children cumulated vsize (KiB) 37672

[startup+12.7003 s]
/proc/loadavg: 1.04 1.04 0.96 2/58 23780
/proc/meminfo: memFree=268692/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=5.26
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 723 42127 0 2 0 0 495 31 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181968 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23780] ppid=23766 vsize=531184 CPUtime=7.32
/proc/23780/stat : 23780 (gringo) R 23766 23766 32685 0 -1 4202496 124074 0 0 0 688 44 0 0 20 0 1 0 41414223 543932416 107599 18446744073709551615 4194304 6531320 140736881518736 140736881515352 5632288 0 0 16781316 16386 0 0 0 17 0 0 0 0 0 0
/proc/23780/statm: 132796 107599 282 571 0 132217 0
Current children cumulated CPU time (s) 12.58
Current children cumulated vsize (KiB) 540396

[startup+25.5004 s]
/proc/loadavg: 1.03 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=9.49
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 933 16 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4855815 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 25.08
Current children cumulated vsize (KiB) 262208

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

[startup+25.6006 s]
/proc/loadavg: 1.03 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=9.59
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 943 16 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4855815 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 25.18
Current children cumulated vsize (KiB) 262208

[startup+38.4004 s]
/proc/loadavg: 1.03 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=22.29
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 2212 17 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4548536 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 37.88
Current children cumulated vsize (KiB) 262208

[startup+44.8004 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=28.65
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 2846 19 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4548180 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 44.24
Current children cumulated vsize (KiB) 262208

[startup+48.0004 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=31.82
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 3163 19 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4855815 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 47.41
Current children cumulated vsize (KiB) 262208

[startup+49.6044 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=33.41
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 3322 19 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219992 4548507 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 49
Current children cumulated vsize (KiB) 262208

[startup+50.0004 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=33.81
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 3362 19 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219736 4300228 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287416 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 49.4
Current children cumulated vsize (KiB) 262208

[startup+50.4004 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=34.21
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69797 0 2 0 3402 19 0 0 20 0 1 0 41415272 235991040 57172 18446744073709551615 4194304 6012874 140737164220672 140737164219736 5251131 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 57172 230 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287336 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 49.8
Current children cumulated vsize (KiB) 262208

[startup+50.5006 s]
/proc/loadavg: 1.02 1.03 0.96 2/59 23782
/proc/meminfo: memFree=440108/1022884 swapFree=0/0
[pid=23766] ppid=23765 vsize=9212 CPUtime=15.58
/proc/23766/stat : 23766 (aspuncud-full) S 23765 23766 32685 0 -1 4202496 764 223681 0 2 0 0 1448 110 20 0 1 0 41413690 9433088 364 18446744073709551615 4194304 5129932 140736088183312 140736088181376 140187084227678 0 65536 16781316 1115778811 0 0 0 17 0 0 0 0 0 0
/proc/23766/statm: 2303 364 303 229 0 63 0
[pid=23781] ppid=23766 vsize=230460 CPUtime=34.31
/proc/23781/stat : 23781 (unclasp) R 23766 23766 32685 0 -1 4202496 69804 0 2 0 3407 24 0 0 20 0 1 0 41415272 235991040 24411 18446744073709551615 4194304 6012874 140737164220672 140737164220072 5474154 0 0 16781316 16386 0 0 0 17 0 0 0 10 0 0
/proc/23781/statm: 57615 24411 237 444 0 57163 0
[pid=23782] ppid=23766 vsize=22536 CPUtime=0.01
/proc/23782/stat : 23782 (parse.py) S 23766 23766 32685 0 -1 4202496 1404 0 10 0 0 1 0 0 20 0 1 0 41415272 23076864 1223 18446744073709551615 4194304 6642060 140737124289216 140737124287384 139652395497248 0 0 16777220 20994 0 0 0 17 0 0 0 12 0 0
/proc/23782/statm: 5634 1223 508 598 0 720 0
Current children cumulated CPU time (s) 49.9
Current children cumulated vsize (KiB) 262208

Child status: 0
Real time (s): 50.5541
CPU time (s): 49.9871
CPU user time (s): 48.575
CPU system time (s): 1.41209
CPU usage (%): 98.8784
Max. virtual memory (cumulated for all children) (KiB): 807988

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 48.575
system time used= 1.41209
maximum resident set size= 660300
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 295939
page faults= 14
swaps= 0
block input operations= 69864
block output operations= 82456
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 199
involuntary context switches= 957

runsolver used 0.104006 second user time and 0.236014 second system time

The end