debug.log

概要

debug.logにはクライアント⇔x-Servlet⇔元コンテンツサーバ間でのHTTPデータが記録されます。

設定

log4j.propertiesで出力の設定を行います。

形式

debug.logに出力される内容について下記に示します
ここでは「x-Servletの基本動作」ページ内の「x-Servlet基本動作図」で1ページの表示完了までを例とします

  • hh:mm:ss は時:分:秒を表します。
  • sessionIDOnetimeType」を "cookie" とした場合の動作です。
[図中①]x-Servletが携帯端末から受信したHTTPリクエストの内容を表します。
INFO [hh:mm:ss] - [BROWSER ->](0:1) null
GET / HTTP/1.1
Host: 192.168.3.158
Content-Type: application/x-www-form-urlencoded
Accept: */*
User-Agent: DoCoMo/2.0 F02A(c100;TB;W24H17)
Max-Forwards: 10
Connection: close
X-Forwarded-For: 203.191.224.249
X-Forwarded-Host: athena.flexfirm.jp
X-Forwarded-Server: athena.flexfirm.jp
[図中②]x-Servletが元コンテンツサーバに送信したHTTPリクエストの内容を表します。
INFO [hh:mm:ss] - [-> WEB] new
GET / HTTP/1.0
Host: 192.168.3.158:8080
Content-Type: application/x-www-form-urlencoded
Accept: */*, image/*
User-Agent: DoCoMo/2.0 F02A(c100;TB;W24H17)
Max-Forwards: 10
Connection: close
X-Forwarded-For: 203.191.224.249
X-Forwarded-Host: athena.flexfirm.jp
X-Forwarded-Server: athena.flexfirm.jp
X-Xp-Remote-Host: 192.168.3.214
X-Xp-Remote-Port: 51842
X-Xp-Host: 192.168.3.158
X-Xp-Port: 80
X-Xp-Host-Header: 192.168.3.158:80
X-Xp-Terminal-Name: F-02A
X-Xp-ML-Name: CHTML
X-Xp-ML-Version: 7.2
X-Xp-Pixel-Width: 240
X-Xp-Pixel-Height: 352
X-Xp-Image-Formats: GIF,JPG
X-Xp-Flash: 3.0
X-Xp-GPS: openiarea
[図中③]x-Servletが元コンテンツサーバから受信したHTTPレスポンスの内容を表します。
INFO [hh:mm:ss] - [<- WEB] new
HTTP/1.1 200 OK
Date: Thu, 24 Mar 2011 08:47:15 GMT
Server: Apache/2.0.63 (Win32)
Set-Cookie: Apache=192.168.3.158.1300956435138810; path=/; expires=Thu, 31-Mar-11 08:47:15 GMT
Last-Modified: Thu, 24 Mar 2011 08:20:59 GMT
ETag: "913b-167-2849af7d"
Accept-Ranges: bytes
Content-Length: 359
Connection: close
Content-Type: text/html

<html>
 <head>
  <meta http-equiv="Content-Type" content="text/html; charset=Shift_JIS">
  <title>テストページ</title>
 </head>
<body>

<img src="img/ff_logo.jpg">
<form method="post" action="t2r.html">
TEXT入力:
<input type="text" name="text1" size="30" value="">
<input type="SUBMIT" value="送信" name="SUBMIT1">
</form>

</body>

</html>
[図中④]x-Servletが画像先読みとして元コンテンツサーバに送信したHTTPリクエストの内容を表します。
INFO [hh:mm:ss] - [-> WEB] new
GET /img/ff_logo.jpg HTTP/1.0
Host: 192.168.3.158:8080
Accept: */*, image/*
User-Agent: DoCoMo/2.0 F02A(c100;TB;W24H17)
Connection: close
Cookie: Apache=192.168.3.158.1300956435138810
[図中④]x-Servletが元コンテンツサーバから受信したHTTPレスポンスの内容を表します。
INFO [hh:mm:ss] - [<- WEB] new
HTTP/1.1 200 OK
Date: Thu, 24 Mar 2011 08:47:15 GMT
Server: Apache/2.0.63 (Win32)
Last-Modified: Thu, 15 Mar 2007 01:05:53 GMT
ETag: "6d8-b20-b97d851a"
Accept-Ranges: bytes
Content-Length: 2848
Connection: close
Content-Type: image/jpeg

[[以下バイナリデータ省略]]

[図中⑤]x-Servletが携帯端末へ送信した変換後HTMLファイルのHTTPレスポンスの内容を表します。

x-Servletが割り当てた「外部向けセッションID」と「内部管理ID」が記録されます。

また、cookie はWebサーバから発行されたものに x-Servletの「管理用トークン」が追加されます。

INFO [hh:mm:ss] - [BROWSER <-](0:1) new->lT66Kcp0mzawOQAadMvAlC JmbbfUeNfhO8RCquYI4kOb
HTTP/1.1 200 OK
Date: Thu, 24 Mar 2011 08:47:15 GMT
Server: Apache/2.0.63 (Win32)
Last-Modified: Thu, 24 Mar 2011 08:20:59 GMT
Etag: "913b-167-2849af7d"
Accept-Ranges: bytes
Content-Length: 435
Connection: close
Content-Type: text/html;charset=Shift_JIS
Pragma: no-cache
Set-Cookie: x-ServletToken=d8a3d4d9-b5c3-407e-af24-4de99ede0aec; domain=athena.flexfirm.jp;
 path=/; expires=Thu, 24-Mar-11 08:57:15 GMT;

<html><head><meta http-equiv="Content-type" content="text/html;charset=Shift_JIS" ><title>テストページ
</title></head><body><img src="img/ff_logo_xpimg_jpg.jpg?_xpxi=lT66Kcp0mzawOQAadMvAlC.0.B8nt6-0.g">
<form action="/" method="post"><input type="hidden" name="_xpxi" value="lT66Kcp0mzawOQAadMvAlC.0.0-0-1-F.F">
TEXT入力:<input type="text" name="0" size="30" value=""><input type="submit" name="1" value="送信">
</form></body></html>

※表示の都合上、BODY部を折り返していますが実際の出力は改行なしの一行となります。

[図中⑫]x-Servletが携帯端末から受信した変換後画像ファイルのHTTPリクエストの内容を表します。
INFO [hh:mm:ss] - [BROWSER ->](1:1) lT66Kcp0mzawOQAadMvAlC
GET /img/ff_logo_xpimg_jpg.jpg?_xpxi=lT66Kcp0mzawOQAadMvAlC.0.B8nt6-0.g HTTP/1.1
Host: 192.168.3.158
Content-Type: application/x-www-form-urlencoded
Accept: */*
User-Agent: DoCoMo/2.0 F02A(c100;TB;W24H17)
Max-Forwards: 10
Connection: close
X-Forwarded-For: 203.191.224.249
X-Forwarded-Host: athena.flexfirm.jp
X-Forwarded-Server: athena.flexfirm.jp
[図中⑬]x-Servletが元コンテンツサーバに送信した画像のHTTPリクエストの内容を表します。
INFO [hh:mm:ss] - [-> WEB] lT66Kcp0mzawOQAadMvAlC
GET /img/ff_logo.jpg HTTP/1.0
Host: 192.168.3.158:8080
Content-Type: application/x-www-form-urlencoded
Accept: */*, image/*
User-Agent: DoCoMo/2.0 F02A(c100;TB;W24H17)
Max-Forwards: 10
Connection: close
X-Forwarded-For: 203.191.224.249
X-Forwarded-Host: athena.flexfirm.jp
X-Forwarded-Server: athena.flexfirm.jp
X-Xp-Remote-Host: 192.168.3.214
X-Xp-Remote-Port: 51843
X-Xp-Host: 192.168.3.158
X-Xp-Port: 80
X-Xp-Host-Header: 192.168.3.158:80
X-Xp-Terminal-Name: F-02A
X-Xp-ML-Name: CHTML
X-Xp-ML-Version: 7.2
X-Xp-Pixel-Width: 240
X-Xp-Pixel-Height: 352
X-Xp-Image-Formats: GIF,JPG
X-Xp-Flash: 3.0
X-Xp-GPS: openiarea
Xp-Rule: No_3
Cookie: Apache=192.168.3.158.1300956435138810
If-Modified-Since: Thu, 15 Mar 2007 01:05:53 GMT
[図中⑭]x-Servletが元コンテンツサーバから受信したHTTPレスポンスの内容を表します。
INFO [hh:mm:ss] - [<- WEB] lT66Kcp0mzawOQAadMvAlC
HTTP/1.1 304 Not Modified
Date: Thu, 24 Mar 2011 08:47:15 GMT
Server: Apache/2.0.63 (Win32)
Connection: close
ETag: "6d8-b20-b97d851a"
[図中⑮]x-Servletが携帯端末に送信した変換後画像ファイルのHTTPレスポンスの内容を表します。

cookie を利用しているので「外部向けセッションID」のワンタイム化は行われず、同じ値が出力されます。

INFO [hh:mm:ss] - [BROWSER <-](1:1) lT66Kcp0mzawOQAadMvAlC->lT66Kcp0mzawOQAadMvAlC JmbbfUeNfhO8RCquYI4kOb
HTTP/1.1 200 OK
Date: Thu, 24 Mar 2011 08:47:15 GMT
Server: Apache/2.0.63 (Win32)
Last-Modified: Thu, 15 Mar 2007 01:05:53 GMT
Etag: "6d8-b20-b97d851a"
Accept-Ranges: bytes
Connection: close
Content-Type: image/jpeg
X-Image-Height: 58
X-Image-Width: 54
Pragma: no-cache
Content-Length: 2010

[[以下バイナリデータ省略]]

ログに追記される「外部向けセッションID」と「内部管理ID」の内容は以下の通りです。

  • 「new」:新規セッションでまだIDが割り当てられていない状態
  • 「invalid」:管理しているものと異なるセッションIDが渡された状態
  • 「 (空白)」:x-Servlet内部でエラーが発生した場合(外部向けセッションID)
  • 「null」:x-Servlet内部でエラーが発生した場合(内部管理ID)
  • それ以外の値:セッションID
TOP