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/218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./apt-get-paranoid-1.0 /home/misc2010/data/2010/debian-dudf//218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf /home/misc2010/tmp/201012061533/apt-get-paranoid-1.0/218091ce-4bcc-11df-bc4e-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.26 1.05 1.04 4/46 7654
/proc/meminfo: memFree=879192/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2576 CPUtime=0
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 365 0 0 0 0 0 0 0 18 0 1 0 874945782 2637824 272 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0
/proc/7653/statm: 644 272 232 194 0 30 0
[pid=7654] ppid=7653 vsize=2576 CPUtime=0
/proc/7654/stat : 7654 (apt-get-paranoi) R 7653 7653 2059 34821 2220 4202560 136 0 0 0 0 0 0 0 25 0 1 0 874945782 2637824 156 1283457024 134512640 135304128 4286890544 18446744073709551615 4159875264 0 0 0 65538 0 0 0 17 0 0 0 0
/proc/7654/statm: 644 156 115 194 0 30 0

[startup+0.202 s]
/proc/loadavg: 1.26 1.05 1.04 4/46 7654
/proc/meminfo: memFree=879192/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2588

[startup+0.312021 s]
/proc/loadavg: 1.26 1.05 1.04 4/46 7654
/proc/meminfo: memFree=879192/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2588

[startup+0.402032 s]
/proc/loadavg: 1.26 1.05 1.04 4/46 7654
/proc/meminfo: memFree=879192/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2588

[startup+0.712113 s]
/proc/loadavg: 1.26 1.05 1.04 4/46 7654
/proc/meminfo: memFree=879192/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
Current children cumulated CPU time (s) 0.05
Current children cumulated vsize (KiB) 2588

[startup+1.51231 s]
/proc/loadavg: 1.26 1.05 1.04 2/47 7664
/proc/meminfo: memFree=835656/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
[pid=7664] ppid=7653 vsize=29784 CPUtime=1.45
/proc/7664/stat : 7664 (cudftodeb.nativ) R 7653 7653 2059 34821 2220 4202496 7030 0 0 0 141 4 0 0 25 0 1 0 874945788 30498816 6957 1283457024 134512640 135004320 4287859600 18446744073709551615 134608595 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/7664/statm: 7446 6957 225 121 0 6838 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 32372

[startup+3.11271 s]
/proc/loadavg: 1.26 1.05 1.04 2/47 7664
/proc/meminfo: memFree=815816/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
[pid=7664] ppid=7653 vsize=58184 CPUtime=3.05
/proc/7664/stat : 7664 (cudftodeb.nativ) R 7653 7653 2059 34821 2220 4202496 13985 0 0 0 298 7 0 0 25 0 1 0 874945788 59580416 13912 1283457024 134512640 135004320 4287859600 18446744073709551615 134946096 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/7664/statm: 14546 13912 225 121 0 13938 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 60772

[startup+6.31372 s]
/proc/loadavg: 1.24 1.05 1.04 2/47 7664
/proc/meminfo: memFree=760264/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2588 CPUtime=0.05
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 566 1856 0 0 0 0 1 4 25 0 1 0 874945782 2650112 283 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 647 283 240 194 0 33 0
[pid=7664] ppid=7653 vsize=112984 CPUtime=6.25
/proc/7664/stat : 7664 (cudftodeb.nativ) R 7653 7653 2059 34821 2220 4202496 27430 0 0 0 612 13 0 0 25 0 1 0 874945788 115695616 27357 1283457024 134512640 135004320 4287859600 18446744073709551615 134850345 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/7664/statm: 28246 27357 237 121 0 27638 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 115572

[startup+12.7067 s]
/proc/loadavg: 1.30 1.06 1.04 1/47 7674
/proc/meminfo: memFree=759452/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2596 CPUtime=9.2
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 700 34069 0 1 0 0 888 32 18 0 1 0 874945782 2658304 294 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 649 294 249 194 0 35 0
[pid=7674] ppid=7653 vsize=31624 CPUtime=1.29
/proc/7674/stat : 7674 (apt-get) R 7653 7653 2059 34821 2220 4202496 10649 0 363 0 118 11 0 0 18 0 1 0 874946795 32382976 4530 1283457024 134512640 134619611 4287207696 18446744073709551615 4159287866 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/7674/statm: 7906 4530 3935 27 0 600 0
Current children cumulated CPU time (s) 10.49
Current children cumulated vsize (KiB) 34220

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

[startup+12.9071 s]
/proc/loadavg: 1.30 1.06 1.04 1/47 7674
/proc/meminfo: memFree=759452/1048576 swapFree=2078760/2097144
[pid=7653] ppid=7652 vsize=2596 CPUtime=9.2
/proc/7653/stat : 7653 (apt-get-paranoi) S 7652 7653 2059 34821 2220 4202496 700 34069 0 1 0 0 888 32 18 0 1 0 874945782 2658304 294 1283457024 134512640 135304128 4286890544 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7653/statm: 649 294 249 194 0 35 0
[pid=7674] ppid=7653 vsize=31976 CPUtime=1.49
/proc/7674/stat : 7674 (apt-get) R 7653 7653 2059 34821 2220 4202496 13218 0 363 0 138 11 0 0 18 0 1 0 874946795 32743424 7098 1283457024 134512640 134619611 4287207696 18446744073709551615 4156429069 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/7674/statm: 7994 7098 6447 27 0 688 0
Current children cumulated CPU time (s) 10.69
Current children cumulated vsize (KiB) 34572

Child status: 100
Real time (s): 12.9536
CPU time (s): 10.7407
CPU user time (s): 10.3006
CPU system time (s): 0.440027
CPU usage (%): 82.9164
Max. virtual memory (cumulated for all children) (KiB): 116812

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 10.3006
system time used= 0.440027
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= 238
involuntary context switches= 266

runsolver used 0.004 second user time and 0 second system time

The end