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 -W 300 -M 1124 -w /home/cbse/results/sarge-etch/logs/138.runsolver.cupt cupt -o APT::Architecture=i386 -s -V -y --no-install-recommends remove psutils libtiff4 libxtst6 libkrb53 synaptic Enforcing wall clock limit (soft limit, will send signal-name then SIGKILL): 300 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: 0.99 0.83 0.42 2/55 24604 /proc/meminfo: memFree=282204/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=3152 CPUtime=0 /proc/24604/stat : 24604 (runsolver) R 24603 17872 17872 34816 17872 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28211508 3227648 32 18446744073709551615 134512640 134586868 4290669696 4290667744 4151690288 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.185279 s] /proc/loadavg: 0.99 0.83 0.42 2/55 24604 /proc/meminfo: memFree=282204/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=37996 CPUtime=0.19 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 2987 7201 0 0 11 2 4 2 20 0 1 0 28211508 38907904 2801 18446744073709551615 4194304 5184042 140733317511280 140733317507224 140136568439446 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 9499 2801 758 242 0 2061 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 37996 [startup+0.200284 s] /proc/loadavg: 0.99 0.83 0.42 2/55 24604 /proc/meminfo: memFree=282204/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=39712 CPUtime=0.21 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 3459 7201 0 0 12 3 4 2 20 0 1 0 28211508 40665088 3270 18446744073709551615 4194304 5184042 140733317511280 140733317507224 140136552067372 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 9928 3270 792 242 0 2490 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 39712 [startup+0.300268 s] /proc/loadavg: 0.99 0.83 0.42 2/55 24604 /proc/meminfo: memFree=282204/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=50368 CPUtime=0.31 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 6184 7201 0 0 19 6 4 2 20 0 1 0 28211508 51576832 5994 18446744073709551615 4194304 5184042 140733317511280 140733317508776 140136568320336 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 12592 5994 842 242 0 5154 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 50368 [startup+0.700213 s] /proc/loadavg: 0.99 0.83 0.42 2/55 24604 /proc/meminfo: memFree=282204/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=56348 CPUtime=0.71 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 7802 7201 0 0 59 6 4 2 20 0 1 0 28211508 57700352 7385 18446744073709551615 4194304 5184042 140733317511280 140733317505544 140136560427376 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 14087 7385 842 242 0 6649 0 Current children cumulated CPU time (s) 0.71 Current children cumulated vsize (KiB) 56348 [startup+1.50023 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=255544/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=57504 CPUtime=1.5 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 8223 7201 0 0 138 6 4 2 20 0 1 0 28211508 58884096 7549 18446744073709551615 4194304 5184042 140733317511280 140733317508728 140136568345987 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 14376 7549 842 242 0 6938 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 57504 [startup+3.10023 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=252816/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=63960 CPUtime=3.09 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 10051 7201 0 0 296 7 4 2 20 0 1 0 28211508 65495040 9377 18446744073709551615 4194304 5184042 140733317511280 140733317505496 140136551997264 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 15990 9377 842 242 0 8552 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 63960 [startup+6.30022 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=242152/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=72544 CPUtime=6.28 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 12577 7201 0 0 612 10 4 2 20 0 1 0 28211508 74285056 11390 18446744073709551615 4194304 5184042 140733317511280 140733317505544 140136552004218 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 18136 11390 842 242 0 10698 0 Current children cumulated CPU time (s) 6.28 Current children cumulated vsize (KiB) 72544 Solver just ended. Dumping a history of the last processes samples [startup+6.40029 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=242152/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=72544 CPUtime=6.38 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 12587 7201 0 0 622 10 4 2 20 0 1 0 28211508 74285056 11400 18446744073709551615 4194304 5184042 140733317511280 140733317505544 140136552002034 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 18136 11400 842 242 0 10698 0 Current children cumulated CPU time (s) 6.38 Current children cumulated vsize (KiB) 72544 [startup+7.20023 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=239672/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=72544 CPUtime=7.18 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 12667 7201 0 0 701 11 4 2 20 0 1 0 28211508 74285056 11480 18446744073709551615 4194304 5184042 140733317511280 140733317505544 140136568320336 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 18136 11480 842 242 0 10698 0 Current children cumulated CPU time (s) 7.18 Current children cumulated vsize (KiB) 72544 [startup+7.60023 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=239672/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=72544 CPUtime=7.58 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 12699 7201 0 0 741 11 4 2 20 0 1 0 28211508 74285056 11512 18446744073709551615 4194304 5184042 140733317511280 140733317505544 140136568346135 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 18136 11512 842 242 0 10698 0 Current children cumulated CPU time (s) 7.58 Current children cumulated vsize (KiB) 72544 [startup+7.70022 s] /proc/loadavg: 0.99 0.83 0.43 2/56 24624 /proc/meminfo: memFree=243020/1022884 swapFree=0/0 [pid=24604] ppid=24603 vsize=68432 CPUtime=7.68 /proc/24604/stat : 24604 (cupt) R 24603 24604 17872 34816 17872 4202496 12714 7201 0 0 751 11 4 2 20 0 1 0 28211508 70074368 10505 18446744073709551615 4194304 5184042 140733317511280 140733317510440 140136568108240 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24604/statm: 17108 10505 853 242 0 9670 0 Current children cumulated CPU time (s) 7.68 Current children cumulated vsize (KiB) 68432 Child status: 1 Real time (s): 7.74577 CPU time (s): 7.74048 CPU user time (s): 7.59247 CPU system time (s): 0.148009 CPU usage (%): 99.9317 Max. virtual memory (cumulated for all children) (KiB): 72544 getrusage(RUSAGE_CHILDREN,...) data: user time used= 7.59247 system time used= 0.148009 maximum resident set size= 46108 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19935 page faults= 0 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 52 involuntary context switches= 131 runsolver used 0.004 second user time and 0.036002 second system time The end