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/688250e8-4bb4-11df-a561-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./apt-get-paranoid-1.0 /home/misc2010/data/2010/debian-dudf//688250e8-4bb4-11df-a561-00163e7a6f5e.cudf /home/misc2010/tmp/201012061533/apt-get-paranoid-1.0/688250e8-4bb4-11df-a561-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.60 1.35 1.23 4/46 25144
/proc/meminfo: memFree=778124/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 517 1358 0 0 0 0 0 0 25 0 1 0 876171666 2646016 280 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 280 237 194 0 32 0
[pid=25144] ppid=25136 vsize=2000 CPUtime=0.03
/proc/25144/stat : 25144 (cp) R 25136 25136 2059 34821 2220 4202496 252 0 0 0 0 3 0 0 25 0 1 0 876171667 2048000 182 1283457024 134512640 134614052 4291559248 18446744073709551615 4294960130 0 0 0 0 0 0 0 17 0 0 0 0
/proc/25144/statm: 500 182 145 25 0 46 0

[startup+0.178551 s]
/proc/loadavg: 1.60 1.35 1.23 4/46 25144
/proc/meminfo: memFree=778124/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2584

[startup+0.208553 s]
/proc/loadavg: 1.60 1.35 1.23 4/46 25144
/proc/meminfo: memFree=778124/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2584

[startup+0.308574 s]
/proc/loadavg: 1.60 1.35 1.23 4/46 25144
/proc/meminfo: memFree=778124/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2584

[startup+0.708626 s]
/proc/loadavg: 1.60 1.35 1.23 4/46 25144
/proc/meminfo: memFree=778124/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2584

[startup+1.50878 s]
/proc/loadavg: 1.55 1.34 1.23 2/47 25147
/proc/meminfo: memFree=745840/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
[pid=25147] ppid=25136 vsize=30776 CPUtime=1.45
/proc/25147/stat : 25147 (cudftodeb.nativ) R 25136 25136 2059 34821 2220 4202496 7265 0 0 0 143 2 0 0 25 0 1 0 876171671 31514624 7192 1283457024 134512640 135004320 4291288784 18446744073709551615 134947979 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/25147/statm: 7694 7192 225 121 0 7086 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 33360

[startup+3.10918 s]
/proc/loadavg: 1.55 1.34 1.23 2/47 25147
/proc/meminfo: memFree=726372/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
[pid=25147] ppid=25136 vsize=58928 CPUtime=3.05
/proc/25147/stat : 25147 (cudftodeb.nativ) R 25136 25136 2059 34821 2220 4202496 14207 0 0 0 300 5 0 0 25 0 1 0 876171671 60342272 14134 1283457024 134512640 135004320 4291288784 18446744073709551615 134996526 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/25147/statm: 14732 14134 225 121 0 14124 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 61512

[startup+6.31013 s]
/proc/loadavg: 1.55 1.34 1.23 2/47 25147
/proc/meminfo: memFree=669828/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2584 CPUtime=0.05
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 566 1856 0 0 0 0 0 5 25 0 1 0 876171666 2646016 283 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 646 283 240 194 0 32 0
[pid=25147] ppid=25136 vsize=113976 CPUtime=6.24
/proc/25147/stat : 25147 (cudftodeb.nativ) R 25136 25136 2059 34821 2220 4202496 27662 0 0 0 612 12 0 0 25 0 1 0 876171671 116711424 27589 1283457024 134512640 135004320 4291288784 18446744073709551615 134996501 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/25147/statm: 28494 27589 237 121 0 27886 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 116560

[startup+12.707 s]
/proc/loadavg: 1.47 1.33 1.22 1/47 25157
/proc/meminfo: memFree=668892/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2592 CPUtime=9.11
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 701 34068 0 1 0 0 878 33 18 0 1 0 876171666 2654208 294 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 648 294 249 194 0 34 0
[pid=25157] ppid=25136 vsize=31624 CPUtime=1.32
/proc/25157/stat : 25157 (apt-get) R 25136 25136 2059 34821 2220 4202496 11913 0 363 0 120 12 0 0 18 0 1 0 876172676 32382976 5794 1283457024 134512640 134619611 4292181456 18446744073709551615 4160275472 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/25157/statm: 7906 5794 5199 27 0 600 0
Current children cumulated CPU time (s) 10.43
Current children cumulated vsize (KiB) 34216

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

[startup+12.807 s]
/proc/loadavg: 1.47 1.33 1.22 1/47 25157
/proc/meminfo: memFree=668892/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2592 CPUtime=9.11
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 701 34068 0 1 0 0 878 33 18 0 1 0 876171666 2654208 294 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 648 294 249 194 0 34 0
[pid=25157] ppid=25136 vsize=31624 CPUtime=1.42
/proc/25157/stat : 25157 (apt-get) R 25136 25136 2059 34821 2220 4202496 13134 0 363 0 130 12 0 0 18 0 1 0 876172676 32382976 7015 1283457024 134512640 134619611 4292181456 18446744073709551615 4159876436 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/25157/statm: 7906 7015 6420 27 0 600 0
Current children cumulated CPU time (s) 10.53
Current children cumulated vsize (KiB) 34216

[startup+12.907 s]
/proc/loadavg: 1.47 1.33 1.22 1/47 25157
/proc/meminfo: memFree=668892/1048576 swapFree=2078744/2097144
[pid=25136] ppid=25135 vsize=2592 CPUtime=9.11
/proc/25136/stat : 25136 (apt-get-paranoi) S 25135 25136 2059 34821 2220 4202496 701 34068 0 1 0 0 878 33 18 0 1 0 876171666 2654208 294 1283457024 134512640 135304128 4294660512 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25136/statm: 648 294 249 194 0 34 0
[pid=25157] ppid=25136 vsize=31800 CPUtime=1.52
/proc/25157/stat : 25157 (apt-get) R 25136 25136 2059 34821 2220 4202496 13253 0 363 0 140 12 0 0 18 0 1 0 876172676 32563200 7089 1283457024 134512640 134619611 4292181456 18446744073709551615 4159947251 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/25157/statm: 7950 7089 6449 27 0 644 0
Current children cumulated CPU time (s) 10.63
Current children cumulated vsize (KiB) 34392

Child status: 100
Real time (s): 12.9361
CPU time (s): 10.6687
CPU user time (s): 10.2046
CPU system time (s): 0.464029
CPU usage (%): 82.472
Max. virtual memory (cumulated for all children) (KiB): 116808

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 10.2046
system time used= 0.464029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 48037
page faults= 364
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 264
involuntary context switches= 229

runsolver used 0 second user time and 0 second system time

The end