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/2f690324-4939-11df-9e6e-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./apt-get-paranoid-1.0 /home/misc2010/data/2010/debian-dudf//2f690324-4939-11df-9e6e-00163e7a6f5e.cudf /home/misc2010/tmp/201012061533/apt-get-paranoid-1.0/2f690324-4939-11df-9e6e-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: 0.87 0.99 1.04 4/46 12701
/proc/meminfo: memFree=804696/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2576 CPUtime=0
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 400 156 0 0 0 0 0 0 18 0 1 0 875408969 2637824 277 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 644 277 235 194 0 30 0
[pid=12701] ppid=12699 vsize=48 CPUtime=0
/proc/12701/stat : 12701 (mkdir) R 12699 12699 2059 34821 2220 4194304 62 0 0 0 0 0 0 0 25 0 1 0 875408970 49152 2 1283457024 0 0 4293616580 18446744073709551615 4294960130 0 0 0 0 0 0 0 17 0 0 0 0
/proc/12701/statm: 12 2 0 0 0 1 0

[startup+0.172586 s]
/proc/loadavg: 0.87 0.99 1.04 4/46 12701
/proc/meminfo: memFree=804696/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
Current children cumulated CPU time (s) 0.06
Current children cumulated vsize (KiB) 2584

[startup+0.20259 s]
/proc/loadavg: 0.87 0.99 1.04 4/46 12701
/proc/meminfo: memFree=804696/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
Current children cumulated CPU time (s) 0.06
Current children cumulated vsize (KiB) 2584

[startup+0.312616 s]
/proc/loadavg: 0.87 0.99 1.04 4/46 12701
/proc/meminfo: memFree=804696/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
Current children cumulated CPU time (s) 0.06
Current children cumulated vsize (KiB) 2584

[startup+0.712681 s]
/proc/loadavg: 0.87 0.99 1.04 4/46 12701
/proc/meminfo: memFree=804696/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
Current children cumulated CPU time (s) 0.06
Current children cumulated vsize (KiB) 2584

[startup+1.51283 s]
/proc/loadavg: 0.87 0.99 1.04 2/47 12710
/proc/meminfo: memFree=762284/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
[pid=12710] ppid=12699 vsize=31020 CPUtime=1.46
/proc/12710/stat : 12710 (cudftodeb.nativ) R 12699 12699 2059 34821 2220 4202496 7319 0 0 0 144 2 0 0 25 0 1 0 875408975 31764480 7245 1283457024 134512640 135004320 4289621312 18446744073709551615 134943984 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/12710/statm: 7755 7245 225 121 0 7147 0
Current children cumulated CPU time (s) 1.52
Current children cumulated vsize (KiB) 33604

[startup+3.11309 s]
/proc/loadavg: 0.87 0.99 1.04 2/47 12710
/proc/meminfo: memFree=742816/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
[pid=12710] ppid=12699 vsize=59916 CPUtime=3.05
/proc/12710/stat : 12710 (cudftodeb.nativ) R 12699 12699 2059 34821 2220 4202496 14408 0 0 0 299 6 0 0 25 0 1 0 875408975 61353984 14334 1283457024 134512640 135004320 4289621312 18446744073709551615 134946161 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/12710/statm: 14979 14334 225 121 0 14371 0
Current children cumulated CPU time (s) 3.11
Current children cumulated vsize (KiB) 62500

[startup+6.31363 s]
/proc/loadavg: 0.88 0.99 1.04 2/47 12710
/proc/meminfo: memFree=684660/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2584 CPUtime=0.06
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 567 1866 0 0 0 0 0 6 25 0 1 0 875408969 2646016 284 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 646 284 240 194 0 32 0
[pid=12710] ppid=12699 vsize=115956 CPUtime=6.24
/proc/12710/stat : 12710 (cudftodeb.nativ) R 12699 12699 2059 34821 2220 4202496 28169 0 0 0 612 12 0 0 25 0 1 0 875408975 118738944 28095 1283457024 134512640 135004320 4289621312 18446744073709551615 4294960130 0 0 0 1024 0 0 0 17 0 0 0 0
/proc/12710/statm: 28989 28095 237 121 0 28381 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 118540

[startup+12.7076 s]
/proc/loadavg: 0.89 0.99 1.04 1/47 12720
/proc/meminfo: memFree=687528/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2592 CPUtime=9.68
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 706 40801 0 1 0 0 938 30 18 0 1 0 875408969 2654208 295 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 648 295 249 194 0 34 0
[pid=12720] ppid=12699 vsize=101456 CPUtime=1.21
/proc/12720/stat : 12720 (apt-get) R 12699 12699 2059 34821 2220 4202496 6328 0 351 0 111 10 0 0 18 0 1 0 875410054 103890944 6602 1283457024 134512640 134619611 4293867376 18446744073709551615 4159503666 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/12720/statm: 25364 6602 6549 27 0 58 0
Current children cumulated CPU time (s) 10.89
Current children cumulated vsize (KiB) 104048

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

[startup+12.8073 s]
/proc/loadavg: 0.89 0.99 1.04 1/47 12720
/proc/meminfo: memFree=687528/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2592 CPUtime=9.68
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 706 40801 0 1 0 0 938 30 18 0 1 0 875408969 2654208 295 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 648 295 249 194 0 34 0
[pid=12720] ppid=12699 vsize=101456 CPUtime=1.26
/proc/12720/stat : 12720 (apt-get) D 12699 12699 2059 34821 2220 4202496 6515 0 362 0 114 12 0 0 18 0 1 0 875410054 103890944 6800 1283457024 134512640 134619611 4293867376 18446744073709551615 4294960130 0 0 4096 134217728 18446744071564332537 0 0 17 0 0 0 0
/proc/12720/statm: 25364 6800 6747 27 0 58 0
Current children cumulated CPU time (s) 10.94
Current children cumulated vsize (KiB) 104048

[startup+13.2073 s]
/proc/loadavg: 0.90 0.99 1.04 1/47 12720
/proc/meminfo: memFree=686016/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2592 CPUtime=9.68
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 706 40801 0 1 0 0 938 30 18 0 1 0 875408969 2654208 295 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 648 295 249 194 0 34 0
[pid=12720] ppid=12699 vsize=101456 CPUtime=1.26
/proc/12720/stat : 12720 (apt-get) R 12699 12699 2059 34821 2220 4202496 6515 0 362 0 114 12 0 0 18 0 1 0 875410054 103890944 6800 1283457024 134512640 134619611 4293867376 18446744073709551615 4294960130 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/12720/statm: 25364 6800 6747 27 0 58 0
Current children cumulated CPU time (s) 10.94
Current children cumulated vsize (KiB) 104048

[startup+13.6115 s]
/proc/loadavg: 0.90 0.99 1.04 1/47 12720
/proc/meminfo: memFree=686016/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2592 CPUtime=9.68
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 706 40801 0 1 0 0 938 30 18 0 1 0 875408969 2654208 295 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 648 295 249 194 0 34 0
[pid=12720] ppid=12699 vsize=31556 CPUtime=1.36
/proc/12720/stat : 12720 (apt-get) R 12699 12699 2059 34821 2220 4202496 13104 0 362 0 122 14 0 0 18 0 1 0 875410054 32313344 6997 1283457024 134512640 134619611 4293867376 18446744073709551615 4159523227 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/12720/statm: 7889 6997 6403 27 0 599 0
Current children cumulated CPU time (s) 11.04
Current children cumulated vsize (KiB) 34148

[startup+13.7115 s]
/proc/loadavg: 0.90 0.99 1.04 1/47 12720
/proc/meminfo: memFree=686016/1048576 swapFree=2078884/2097144
[pid=12699] ppid=12698 vsize=2592 CPUtime=9.68
/proc/12699/stat : 12699 (apt-get-paranoi) S 12698 12699 2059 34821 2220 4202496 706 40801 0 1 0 0 938 30 18 0 1 0 875408969 2654208 295 1283457024 134512640 135304128 4290397584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/12699/statm: 648 295 249 194 0 34 0
[pid=12720] ppid=12699 vsize=31556 CPUtime=1.46
/proc/12720/stat : 12720 (apt-get) R 12699 12699 2059 34821 2220 4202496 13110 0 362 0 132 14 0 0 18 0 1 0 875410054 32313344 7003 1283457024 134512640 134619611 4293867376 18446744073709551615 4159523173 0 0 4096 134217728 0 0 0 17 0 0 0 0
/proc/12720/statm: 7889 7003 6409 27 0 599 0
Current children cumulated CPU time (s) 11.14
Current children cumulated vsize (KiB) 34148

Child status: 100
Real time (s): 13.7983
CPU time (s): 11.2327
CPU user time (s): 10.7687
CPU system time (s): 0.464029
CPU usage (%): 81.4062
Max. virtual memory (cumulated for all children) (KiB): 148796

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 10.7687
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= 54747
page faults= 363
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 263
involuntary context switches= 252

runsolver used 0 second user time and 0 second system time

The end