JSPs not found... sometimes

[Allen Hopkins <al...@eecs.berkeley.edu>]

I'm upgrading an existing installation from Tomcat 3.2.1 to 4.0.3.
Here's my platform:
    Sun Ultra-60
    Solaris 2.8
    JDK 1.4.0_01
    J2EE 1.3.1
    Apache 3.1.19
    Tomcat 4.0.3
    mod_jk 1.2.0

Sometimes when I start things up, Apache claims my JSPs are not
found, for example:
"The requested URL /gsrcj/resetpassword.jsp was not found on this server."

Other times, the system comes up working fine.  Whether it
works or not when it comes up seems random, as far as I can
tell, but it usually fails.  Between restarts, its behavior
is consistent (either it's working or it's not).

I've turned on debug-level logging, and mod_jk.log shows a
difference between a successful startup and a failed
startup.  I'm hoping someone on the list knows what to make
of this output to help me fix this.  Here is a diff of
mod_jk.log saved while starting up, working and not
working, and the full output is below.  It says "rule map
size is 2" when it works, and "rule map size is 0" when it
doesn't:

33,36c33
< [jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map size is 2
< [jk_uri_worker_map.c (345)]: Into jk_uri_worker_map_t::uri_worker_map_open, exact rule /gsrcj=ajp13 was added
< [jk_uri_worker_map.c (321)]: Into jk_uri_worker_map_t::uri_worker_map_open, match rule /gsrcj/=ajp13 was added
< [jk_uri_worker_map.c (408)]: Into jk_uri_worker_map_t::uri_worker_map_open, there are 2 rules
---
> [jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map size is 0

Any help is appreciated.  I'm at a loss.

Here is my server.xml file, in its entirety (55 lines):
------- 8< --------------------------------------------------------
<Server port="8005" shutdown="SHUTDOWN" debug="10">
  <Listener className="org.apache.ajp.tomcat4.config.ApacheConfig"
            jkDebug="debug"/>

  <Service name="Tomcat-Standalone">

    <Connector className="org.apache.ajp.tomcat4.Ajp13Connector"
               port="8009" minProcessors="5" maxProcessors="75"
               acceptCount="10" debug="10"/>

    <Engine name="Standalone" defaultHost="localhost" debug="10">

      <Logger className="org.apache.catalina.logger.FileLogger"
              verbosity="4"
              prefix="catalina_log."
              suffix=".txt"
              timestamp="true"/>

      <Realm className="org.apache.catalina.realm.MemoryRealm" />

      <Host name="localhost" debug="10" appBase="webapps" unpackWARs="true">

        <Valve className="org.apache.catalina.valves.AccessLogValve"
                 directory="logs"  prefix="localhost_access_log." suffix=".txt"
                 pattern="common"/>

        <Logger className="org.apache.catalina.logger.FileLogger"
                verbosity="4"
                directory="logs"
                prefix="localhost_log."
                suffix=".txt"
                timestamp="true"/>

        <Context path="/gsrcj"
                 docBase="/home/www/gsrcwww/java"
                 debug="10"
                 reloadable="true"
                 crossContext="true">
          <Listener className="org.apache.ajp.tomcat4.config.ApacheConfig"
                    forwardAll="true"
                    noRoot="true"
                    jkDebug="debug"
                    append="true"/>
          <Logger className="org.apache.catalina.logger.FileLogger"
                  verbosity="4"
                  prefix="gsrc_log."
                  suffix=".txt"
                  timestamp="true"/>
          <Parameter name="appURLBase" value="/gsrcj" override="true"/>
        </Context>

      </Host>
    </Engine>
  </Service>
</Server>
------- 8< --------------------------------------------------------


Here is the full output of mod_jk during a successful startup
(without timetags):

------- 8< --------------------------------------------------------
[jk_uri_worker_map.c (172)]: Into jk_uri_worker_map_t::uri_worker_map_alloc
[jk_uri_worker_map.c (375)]: Into jk_uri_worker_map_t::uri_worker_map_open
[jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map
size is 2
[jk_uri_worker_map.c (345)]: Into jk_uri_worker_map_t::uri_worker_map_open, exact rule /gsrcj=ajp13 was added
[jk_uri_worker_map.c (321)]: Into jk_uri_worker_map_t::uri_worker_map_open, match rule /gsrcj/=ajp13 was added
[jk_uri_worker_map.c (408)]: Into jk_uri_worker_map_t::uri_worker_map_open, there are 2 rules
[jk_uri_worker_map.c (422)]: jk_uri_worker_map_t::uri_worker_map_open, done
[jk_worker.c (88)]: Into wc_open
[jk_worker.c (222)]: Into build_worker_map, creating 2 workers
[jk_worker.c (228)]: build_worker_map, creating worker ajp12
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp12 of ajp12
[jk_ajp12_worker.c (268)]: Into ajp12_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp12
[jk_ajp12_worker.c (185)]: Into jk_worker_t::validate
[jk_ajp12_worker.c (197)]: In jk_worker_t::validate for worker ajp12 contact is
localhost:8007
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp12 worker
[jk_worker.c (228)]: build_worker_map, creating worker ajp13
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp13 of ajp13
[jk_ajp13_worker.c (108)]: Into ajp13_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp13
[jk_ajp_common.c (1178)]: Into jk_worker_t::validate
[jk_ajp_common.c (1198)]: In jk_worker_t::validate for worker ajp13 contact is localhost:8009
[jk_ajp_common.c (1226)]: Into jk_worker_t::init
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp13 worker
[jk_worker.c (250)]: build_worker_map, done
[jk_worker.c (111)]: wc_open, done 2
[jk_uri_worker_map.c (172)]: Into jk_uri_worker_map_t::uri_worker_map_alloc
[jk_uri_worker_map.c (375)]: Into jk_uri_worker_map_t::uri_worker_map_open
[jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map
size is 2
[jk_uri_worker_map.c (345)]: Into jk_uri_worker_map_t::uri_worker_map_open, exact rule /gsrcj=ajp13 was added
[jk_uri_worker_map.c (321)]: Into jk_uri_worker_map_t::uri_worker_map_open, match rule /gsrcj/=ajp13 was added
[jk_uri_worker_map.c (408)]: Into jk_uri_worker_map_t::uri_worker_map_open, there are 2 rules
[jk_uri_worker_map.c (422)]: jk_uri_worker_map_t::uri_worker_map_open, done
[jk_worker.c (88)]: Into wc_open
[jk_worker.c (222)]: Into build_worker_map, creating 2 workers
[jk_worker.c (228)]: build_worker_map, creating worker ajp12
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp12 of ajp12
[jk_ajp12_worker.c (268)]: Into ajp12_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp12
[jk_ajp12_worker.c (185)]: Into jk_worker_t::validate
[jk_ajp12_worker.c (197)]: In jk_worker_t::validate for worker ajp12 contact is
localhost:8007
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp12 worker
[jk_worker.c (228)]: build_worker_map, creating worker ajp13
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp13 of ajp13
[jk_ajp13_worker.c (108)]: Into ajp13_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp13
[jk_ajp_common.c (1178)]: Into jk_worker_t::validate
[jk_ajp_common.c (1198)]: In jk_worker_t::validate for worker ajp13 contact is localhost:8009
[jk_ajp_common.c (1226)]: Into jk_worker_t::init
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp13 worker
[jk_worker.c (250)]: build_worker_map, done
[jk_worker.c (111)]: wc_open, done 2
------- 8< --------------------------------------------------------


Here is the full output of mod_jk during a failed startup:
(See the top of the message for the differences between these.)

------- 8< --------------------------------------------------------
[jk_uri_worker_map.c (172)]: Into jk_uri_worker_map_t::uri_worker_map_alloc
[jk_uri_worker_map.c (375)]: Into jk_uri_worker_map_t::uri_worker_map_open
[jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map
size is 2
[jk_uri_worker_map.c (345)]: Into jk_uri_worker_map_t::uri_worker_map_open, exact rule /gsrcj=ajp13 was added
[jk_uri_worker_map.c (321)]: Into jk_uri_worker_map_t::uri_worker_map_open, match rule /gsrcj/=ajp13 was added
[jk_uri_worker_map.c (408)]: Into jk_uri_worker_map_t::uri_worker_map_open, there are 2 rules
[jk_uri_worker_map.c (422)]: jk_uri_worker_map_t::uri_worker_map_open, done
[jk_worker.c (88)]: Into wc_open
[jk_worker.c (222)]: Into build_worker_map, creating 2 workers
[jk_worker.c (228)]: build_worker_map, creating worker ajp12
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp12 of ajp12
[jk_ajp12_worker.c (268)]: Into ajp12_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp12
[jk_ajp12_worker.c (185)]: Into jk_worker_t::validate
[jk_ajp12_worker.c (197)]: In jk_worker_t::validate for worker ajp12 contact is
localhost:8007
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp12 worker
[jk_worker.c (228)]: build_worker_map, creating worker ajp13
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp13 of ajp13
[jk_ajp13_worker.c (108)]: Into ajp13_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp13
[jk_ajp_common.c (1178)]: Into jk_worker_t::validate
[jk_ajp_common.c (1198)]: In jk_worker_t::validate for worker ajp13 contact is localhost:8009
[jk_ajp_common.c (1226)]: Into jk_worker_t::init
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp13 worker
[jk_worker.c (250)]: build_worker_map, done
[jk_worker.c (111)]: wc_open, done 2
[jk_uri_worker_map.c (172)]: Into jk_uri_worker_map_t::uri_worker_map_alloc
[jk_uri_worker_map.c (375)]: Into jk_uri_worker_map_t::uri_worker_map_open
[jk_uri_worker_map.c (396)]: jk_uri_worker_map_t::uri_worker_map_open, rule map
size is 0
[jk_uri_worker_map.c (422)]: jk_uri_worker_map_t::uri_worker_map_open, done
[jk_worker.c (88)]: Into wc_open
[jk_worker.c (222)]: Into build_worker_map, creating 2 workers
[jk_worker.c (228)]: build_worker_map, creating worker ajp12
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp12 of ajp12
[jk_ajp12_worker.c (268)]: Into ajp12_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp12
[jk_ajp12_worker.c (185)]: Into jk_worker_t::validate
[jk_ajp12_worker.c (197)]: In jk_worker_t::validate for worker ajp12 contact is
localhost:8007
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp12 worker
[jk_worker.c (228)]: build_worker_map, creating worker ajp13
[jk_worker.c (148)]: Into wc_create_worker
[jk_worker.c (162)]: wc_create_worker, about to create instance ajp13 of ajp13
[jk_ajp13_worker.c (108)]: Into ajp13_worker_factory
[jk_worker.c (171)]: wc_create_worker, about to validate and init ajp13
[jk_ajp_common.c (1178)]: Into jk_worker_t::validate
[jk_ajp_common.c (1198)]: In jk_worker_t::validate for worker ajp13 contact is localhost:8009
[jk_ajp_common.c (1226)]: Into jk_worker_t::init
[jk_worker.c (187)]: wc_create_worker, done
[jk_worker.c (238)]: build_worker_map, removing old ajp13 worker
[jk_worker.c (250)]: build_worker_map, done
[jk_worker.c (111)]: wc_open, done 2
------- 8< --------------------------------------------------------

-Allen Hopkins

--
To unsubscribe, e-mail:   <ma...@jakarta.apache.org>
For additional commands, e-mail: <ma...@jakarta.apache.org>