Dotaz: jetty 7 - samostatné logování requestů pro každou webapp

[Jiří Svoboda]

Zkusil jsem místo jetty verze 6 nasadit verzi 7 a nedaří se mi zprovoznit samostatné logování pro každou aplikaci.

U verze 6 má třída WebAppContext metodu addHandler(), takže stačí do příslušného <context>.xml dopsat pár řádků, jak je uvedeno např. na http://docs.codehaus.org/display/JETTY/Logging+Requests dole.

U verze 7 třída WebAppContext metodu addHandler() nemá, takže tento postup nefunguje. Tuto metodu jsem ale našel u třídy HandlerCollection, ovšem nevím, jak to do konfiguračního souboru zakomponovat. Navíc se zdá, že instance HandlerCollection je společná pro třídu Server, takže nevím, zda je vhodné/možné tam přidávat handlery pro jednotlivé webapps. Nebo je třeba založit vlastní instanci?

Jsem zmaten a google nepomáhá. Našel jsem pár podobných otázek, ale bez odpovědi.

---

---

Odpovědi

[Filip Jirsák]

Použijte ContextHandler, do něj vložte HandlerCollection, který postupně zavolá všechny vložené handlery bez ohledu na výsledek předchozího handleru, a do něj vložte postupně WebAppContext a po něm RequestLogHandler.

[Jiří Svoboda]

Díky za odpověď. Na jejím podkladě jsem sesmolil tohle:

<?xml version="1.0"  encoding="ISO-8859-1"?>
<!DOCTYPE Configure PUBLIC "-//Jetty//Configure//EN" "http://www.eclipse.org/jetty/configure.dtd">

<Configure class="org.eclipse.jetty.server.handler.ContextHandler">
  <Set name="contextPath">/</Set>
  <Set name="resourceBase">
    <SystemProperty name="jetty.home" default="."/>/webapps/neco.cz
  </Set>
  <Set name="virtualHosts">
    <Array type="java.lang.String">
      <Item>local.neco.cz</Item>
    </Array>
  </Set>

  <New class="org.eclipse.jetty.server.handler.HandlerCollection">
    <Call name="addHandler">
      <Arg>
        <New class="org.eclipse.jetty.server.handler.RequestLogHandler">
          <Set name="requestLog">
            <New id="RequestLogImpl" class="org.eclipse.jetty.server.NCSARequestLog">
              <Arg>
                <SystemProperty name="jetty.logs" default="./logs"/>/yyyy_mm_dd-neco.cz-request.log
              </Arg>
              <Set name="retainDays">90</Set>
              <Set name="append">true</Set>
              <Set name="extended">true</Set>
              <Set name="logCookies">false</Set>
              <Set name="LogTimeZone">GMT</Set>
            </New>
          </Set>
        </New>
      </Arg>
    </Call>
  </New>
  <New class="org.eclipse.jetty.webapp.WebAppContext">
  </New>
</Configure>

Nejen, že to neloguje (resp. ani soubor pro log neotevře), ale navíc místo obsahu patřičného virtualhosta posílá obsah default contextu. Zkoušel jsem <Set name="virtualHosts"> ... přmístit do WebAppContext, ale výsledek je stejný.

Přiznávám, že některá zákoutí konfigurace jetty jsou pro mne zatím španělská vesnice, hodně tápu. A dokumentace se mi zdá na rozdíl od jiných projektů dost neuchopitelná.

[Filip Jirsák]

Zkuste to takhle:

<?xml version="1.0"  encoding="ISO-8859-1"?>
<!DOCTYPE Configure PUBLIC "-//Jetty//Configure//EN" "http://www.eclipse.org/jetty/configure.dtd">

<Configure class="org.eclipse.jetty.server.handler.ContextHandler">
  <Set name="contextPath">/</Set>
  <Set name="virtualHosts">
    <Array type="java.lang.String">
      <Item>local.neco.cz</Item>
    </Array>
  </Set>

  <Set name="handler">
		<New class="org.eclipse.jetty.server.handler.HandlerCollection">
			<Call name="addHandler">
				<Arg>
					<New class="org.eclipse.jetty.webapp.WebAppContext">
						<Set name="contextPath">/</Set>
						<Set name="resourceBase">
							<SystemProperty name="jetty.home" default="."/>/webapps/neco.cz
						</Set>
					</New>
				</Arg>
			</Call>
			<Call name="addHandler">
				<Arg>
					<New class="org.eclipse.jetty.server.handler.RequestLogHandler">
						<Set name="requestLog">
							<New id="RequestLogImpl" class="org.eclipse.jetty.server.NCSARequestLog">
								<Arg>
									<SystemProperty name="jetty.logs" default="./logs"/>/yyyy_mm_dd-neco.cz-request.log
								</Arg>
								<Set name="retainDays">90</Set>
								<Set name="append">true</Set>
								<Set name="extended">true</Set>
								<Set name="logCookies">false</Set>
								<Set name="LogTimeZone">GMT</Set>
							</New>
						</Set>
					</New>
				</Arg>
			</Call>
		</New>
  </set>
</Configure>

[Jiří Svoboda]

Máme tu posun. V zásadě to funguje, tedy především to začalo logovat. Teď to má tu vadu, že pokud cesta končí lomítkem, tak neposkytne index.jsp, ale dostanu java.lang.StackOverflowError. Pokud napíšu úplnou cestu, je to v pořádku. To už je ale jiný problém, mrknu na to zítra (zřejmě chybí nastavení, které jsem předtím odněkud dědil).

[Filip Jirsák]

To vypadá, že se to zacyklí při interním přesměrovávání. Nejsem si jist, jak fungují ty kontexty vnořené do sebe – ale v tom ContextHanlderu contextPath určitě musí být nastaven, aby se logovala jen příslušná webová aplikace (i když zrovna v tomhle případě je webová aplikace v rootu, takže by to vyšlo nastejno, i kdyby se logovalo vše).

[Jiří Svoboda]

Tak jsem nad tím dnes strávil zase pár hodin... Nejprve jsem se to pokoušel udělat pomocí jinak seskládaných objektů v konfiguraci, aby těch handlerů nebylo tolik v sobě (dnes jsem toho hodně pochopil). Zdá se ale, že jediná použitelná kombinace je ta vámi nabídnutá. Takže jsem se zaměřil na tu výjimku.

Zacyklí se to opravu při forwardu, ale konkrétně to vypadá takto:

...
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.setAttribute(Dispatcher.java:474)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.removeAttribute(Dispatcher.java:495)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.setAttribute(Dispatcher.java:474)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.removeAttribute(Dispatcher.java:495)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.setAttribute(Dispatcher.java:474)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.removeAttribute(Dispatcher.java:495)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.setAttribute(Dispatcher.java:474)
at org.eclipse.jetty.server.Dispatcher$ForwardAttributes.removeAttribute(Dispatcher.java:495)
at org.eclipse.jetty.server.Request.removeAttribute(Request.java:1371)
at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:212)
at org.eclipse.jetty.server.Dispatcher.forward(Dispatcher.java:115)
...

Metoda removeAttribute(name) volá setAttribute(name, null) a ta při splnění určitých podmínek (které jsou v tomto případě evidentně splněny) volá opět removeAttribute(name).

Podle dalšího pohledu do zdrojáku se zdá, že pravou příčinou je zajímavě okomentovaný řádek:

request.removeAttribute(__JSP_FILE); // TODO remove when glassfish 1044 is fixed

Takže jsem asi narazil na chybu, která vznikla ja dočasný workaround chyby 1044 v Glassfish.

A teď, babo raď...

[Filip Jirsák]

To ale máte nějakou starší verzi (před stabilní 7.0.0), ne? Podle mne se takhle kód, který je už v 7.0.0 stable zacyklit nemůže… I když u těch pre verzí by se to normálně takhle zacyklit taky nemělo, ale možná ten kód v některé verzi vypadal ještě jinak. Každopádně ten řádek s __JSP_FILE s tím nesouvisí. Pokud používáte nějakou starší verzi, zkuste nejnovější stabilní, a jinak napište, jakou verzi používáte – protože tohle spíš vypadá na chybu v Jetty (kdyby to byla chyba v konfiguraci, muselo by se to zacyklit už dřív).

[Jiří Svoboda]

Dělá mi to ve verzích jetty-hightide-7.0.1.v20091125 a jetty-hightide-7.1.0.v20100505. Jiné verze jsem nezkoušel. Hightide používám, protože na rozdíl od eclipse verze má podporu JSP.

Jako runtime pouzivam jdk1.6.0_18.

Podle vás to mám zkusit nahlásit?

[Jiří Svoboda]

V pátek jsem to nahlásil jako bug. Někdo se ozval a ještě mě požádal o debug log. Takže uvidíme.

[Jiří Svoboda]

Takže skoro vyřešeno.

Ta nekonečná smyčka sama o sobě chybou nebyla, způsobovaly ji opravdu ty do sebe vnořené kontexty.

Bug report však poukázal na to, že Jetty 7 opravdu neumí logovat pro každý kontext zvlášť. Dnes ráno jeden z vývojářů doplnil podporu této vlastnosti do zdrojáků.

Bug report a jeho příjemný důsledek je zde: http://jira.codehaus.org/browse/JETTY-1231