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/201012061533/apt-get-paranoid-1.0/412959c6-e965-11de-8ebf-00163e6585dd.cudf.debian-dudf.log.runsolver ./apt-get-paranoid-1.0 /home/misc2010/data/2010/debian-dudf//412959c6-e965-11de-8ebf-00163e6585dd.cudf /home/misc2010/tmp/201012061533/apt-get-paranoid-1.0/412959c6-e965-11de-8ebf-00163e6585dd.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: 0.98 1.06 1.09 5/46 17805
/proc/meminfo: memFree=896532/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2572 CPUtime=0
/proc/17804/stat : 17804 (apt-get-paranoi) R 17803 17804 2059 34821 2220 4202496 358 0 0 0 0 0 0 0 25 0 1 0 875817594 2633728 272 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/17804/statm: 643 272 232 194 0 29 0
[pid=17805] ppid=17804 vsize=2572 CPUtime=0
/proc/17805/stat : 17805 (apt-get-paranoi) R 17804 17804 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 875817594 2633728 40 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/17805/statm: 643 40 0 194 0 29 0

[startup+0.175335 s]
/proc/loadavg: 0.98 1.06 1.09 5/46 17805
/proc/meminfo: memFree=896532/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 2584

[startup+0.21534 s]
/proc/loadavg: 0.98 1.06 1.09 5/46 17805
/proc/meminfo: memFree=896532/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 2584

[startup+0.315358 s]
/proc/loadavg: 0.98 1.06 1.09 5/46 17805
/proc/meminfo: memFree=896532/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 2584

[startup+0.715425 s]
/proc/loadavg: 0.98 1.06 1.09 5/46 17805
/proc/meminfo: memFree=896532/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 2584

[startup+1.51557 s]
/proc/loadavg: 0.98 1.06 1.09 2/47 17815
/proc/meminfo: memFree=865992/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
[pid=17815] ppid=17804 vsize=31200 CPUtime=1.48
/proc/17815/stat : 17815 (cudftodeb.nativ) R 17804 17804 2059 34821 2220 4202496 7366 0 0 0 144 4 0 0 25 0 1 0 875817597 31948800 7293 1283457024 134512640 135004320 4291139248 18446744073709551615 134948065 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/17815/statm: 7800 7293 225 121 0 7192 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 33784

[startup+3.1159 s]
/proc/loadavg: 0.98 1.06 1.09 2/47 17815
/proc/meminfo: memFree=842928/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
[pid=17815] ppid=17804 vsize=58764 CPUtime=3.09
/proc/17815/stat : 17815 (cudftodeb.nativ) R 17804 17804 2059 34821 2220 4202496 14201 0 0 0 299 10 0 0 25 0 1 0 875817597 60174336 14128 1283457024 134512640 135004320 4291139248 18446744073709551615 134871363 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/17815/statm: 14691 14128 237 121 0 14083 0
Current children cumulated CPU time (s) 3.11
Current children cumulated vsize (KiB) 61348

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

[startup+3.21592 s]
/proc/loadavg: 0.98 1.06 1.09 2/47 17815
/proc/meminfo: memFree=842928/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2584 CPUtime=0.02
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 565 1860 0 0 0 0 0 2 25 0 1 0 875817594 2646016 283 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 646 283 240 194 0 32 0
[pid=17815] ppid=17804 vsize=58764 CPUtime=3.18
/proc/17815/stat : 17815 (cudftodeb.nativ) R 17804 17804 2059 34821 2220 4202496 14201 0 0 0 308 10 0 0 25 0 1 0 875817597 60174336 14128 1283457024 134512640 135004320 4291139248 18446744073709551615 134852665 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/17815/statm: 14691 14128 237 121 0 14083 0
Current children cumulated CPU time (s) 3.2
Current children cumulated vsize (KiB) 61348

[startup+4.81285 s]
/proc/loadavg: 0.98 1.06 1.09 2/47 17823
/proc/meminfo: memFree=865376/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2592 CPUtime=3.9
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 700 19339 0 1 0 0 372 18 16 0 1 0 875817594 2654208 294 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 648 294 249 194 0 34 0
Current children cumulated CPU time (s) 3.9
Current children cumulated vsize (KiB) 2592

[startup+5.20767 s]
/proc/loadavg: 0.98 1.06 1.09 2/47 17823
/proc/meminfo: memFree=865376/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2592 CPUtime=3.9
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 700 19339 0 1 0 0 372 18 16 0 1 0 875817594 2654208 294 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 648 294 249 194 0 34 0
Current children cumulated CPU time (s) 3.9
Current children cumulated vsize (KiB) 2592

[startup+5.60761 s]
/proc/loadavg: 0.98 1.06 1.09 1/47 17825
/proc/meminfo: memFree=841176/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2592 CPUtime=3.9
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 700 19339 0 1 0 0 372 18 16 0 1 0 875817594 2654208 294 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 648 294 249 194 0 34 0
[pid=17825] ppid=17804 vsize=101452 CPUtime=0.51
/proc/17825/stat : 17825 (apt-get) D 17804 17804 2059 34821 2220 4202496 3394 0 173 0 47 4 0 0 18 0 1 0 875818043 103886848 3491 1283457024 134512640 134619611 4291911056 18446744073709551615 4294960130 0 0 4096 134217728 18446744071696460772 0 0 17 0 0 0 0
/proc/17825/statm: 25363 3491 3438 27 0 57 0
Current children cumulated CPU time (s) 4.41
Current children cumulated vsize (KiB) 104044

[startup+5.70763 s]
/proc/loadavg: 0.98 1.06 1.09 1/47 17825
/proc/meminfo: memFree=841176/1048576 swapFree=2078744/2097144
[pid=17804] ppid=17803 vsize=2592 CPUtime=3.9
/proc/17804/stat : 17804 (apt-get-paranoi) S 17803 17804 2059 34821 2220 4202496 700 19339 0 1 0 0 372 18 16 0 1 0 875817594 2654208 294 1283457024 134512640 135304128 4288945184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/17804/statm: 648 294 249 194 0 34 0
[pid=17825] ppid=17804 vsize=17748 CPUtime=0.56
/proc/17825/stat : 17825 (apt-get) R 17804 17804 2059 34821 2220 4202496 6713 0 173 0 52 4 0 0 18 0 1 0 875818043 18173952 3727 1283457024 134512640 134619611 4291911056 18446744073709551615 4159633765 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/17825/statm: 4437 3727 3280 27 0 451 0
Current children cumulated CPU time (s) 4.46
Current children cumulated vsize (KiB) 20340

Child status: 100
Real time (s): 5.78365
CPU time (s): 4.55228
CPU user time (s): 4.31227
CPU system time (s): 0.240015
CPU usage (%): 78.7095
Max. virtual memory (cumulated for all children) (KiB): 104096

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 4.31227
system time used= 0.240015
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 26865
page faults= 174
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 114
involuntary context switches= 124

runsolver used 0 second user time and 0 second system time

The end