9iAS R2 Apache access_log growth due to dmsoc4j Spy

                   
                   9iAS R2 Apache access_log growth due to dmsoc4j Spy


PROBLEM:
--------
File system usage growth rate is high after installation of 9iAS R2 - research 
shows that the following two files are growing very rapidly:

   /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log
   /u01/app/oracle/product/ora9ias_mt/Apache/Apache/logs/access_log


SOLUTION:
----------

Trim access_log or implement solution documented in metalink note# 226873.1 
Subject:  How to Control the Size of the Oracle HTTP Server  


echo "`date`: trimmed" > /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log
echo "`date`: trimmed" > /u01/app/oracle/product/ora9ias_mt/Apache/Apache/logs/access_log


Before logs are trimmed:

root.somebox.com# df -k
Filesystem            kbytes    used   avail capacity  Mounted on
/dev/dsk/c0t0d0s0    6789100  925264 5795945    14%    /
/proc                      0       0       0     0%    /proc
fd                         0       0       0     0%    /dev/fd
mnttab                     0       0       0     0%    /etc/mnttab
swap                 1583640      40 1583600     1%    /var/run
swap                 1583616      16 1583600     1%    /tmp
/dev/dsk/c0t1d0s0    17410894 16892284  344502    99%    /u01


root.somebox.com# echo "`date`: trimmed" > /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log

root.somebox.com# echo "`date`: trimmed" > /u01/app/oracle/product/ora9ias_mt/Apache/Apache/logs/access_log


After logs are trimmed:

root.somebox.com# df -k
Filesystem            kbytes    used   avail capacity  Mounted on
/dev/dsk/c0t0d0s0    6789100  925272 5795937    14%    /
/proc                      0       0       0     0%    /proc
fd                         0       0       0     0%    /dev/fd
mnttab                     0       0       0     0%    /etc/mnttab
swap                 1583600      40 1583560     1%    /var/run
swap                 1583576      16 1583560     1%    /tmp
/dev/dsk/c0t1d0s0    17410894 14049003 3187783    82%    /u01
root.somebox.com#


Note the growth ratio:

root.somebox.com# date;grep "dmsoc4j" /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log | wc -l
Tue May 25 14:55:56 PDT 2004
     138
     
root.somebox.com# date;grep "dmsoc4j" /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log | wc -l
Tue May 25 14:56:23 PDT 2004
     147

root.somebox.com# grep "dmsoc4j" /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log | head
somebox.com - - [25/May/2004:14:48:22 -0700] "GET /dmsoc4j/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 8363
somebox.com - - [25/May/2004:14:48:22 -0700] "GET /dmsoc4j/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 13200
somebox.com - - [25/May/2004:14:48:22 -0700] "GET /dmsoc4j/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 8361
somebox.com - - [25/May/2004:14:48:29 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 4428
somebox.com - - [25/May/2004:14:48:29 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_ejb_method&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 9474
somebox.com - - [25/May/2004:14:48:29 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 4433
somebox.com - - [25/May/2004:14:48:44 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 4426
somebox.com - - [25/May/2004:14:48:44 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_ejb_method&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 9471
somebox.com - - [25/May/2004:14:48:44 -0700] "GET /dmsoc4j/Spy?nountype=oc4j_servlet&nountype=oc4j_web_module&nountype=oc4j_context&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 4432
somebox.com - - [25/May/2004:14:48:52 -0700] "GET /dmsoc4j/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 8363


root.somebox.com# date;grep "dms0" /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log | wc -l
Tue May 25 15:01:13 PDT 2004
      83

root.somebox.com# grep "dms0" /u01/app/oracle/product/ora9ias/Apache/Apache/logs/access_log | head
somebox.com - - [25/May/2004:14:48:22 -0700] "GET /dms0/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 97042
somebox.com - - [25/May/2004:14:48:29 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274
somebox.com - - [25/May/2004:14:48:44 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274
somebox.com - - [25/May/2004:14:48:52 -0700] "GET /dms0/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 97042
somebox.com - - [25/May/2004:14:48:59 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274
somebox.com - - [25/May/2004:14:49:09 -0700] "GET /dms0/Spy?nountype=ohs_virtualHost&nountype=dms_cProcessInfo&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 1551
somebox.com - - [25/May/2004:14:49:14 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274
somebox.com - - [25/May/2004:14:49:22 -0700] "GET /dms0/Spy?recurse=all&format=xml&operation=get&value=false&units=true&description=true&name=%2F HTTP/1.1" 200 97042
somebox.com - - [25/May/2004:14:49:29 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274
somebox.com - - [25/May/2004:14:49:44 -0700] "GET /dms0/Spy?nountype=ohs_server&nountype=ohs_virtualHost&nountype=dms_cProcessInfo&nountype=ohs_module&recurse=children&format=xml&operation=get&value=true&units=false&description=false HTTP/1.1" 200 36274

.