[02/03/15 17:15] プロクシ使用時に無反応になり errors.logに大量のエラーメッセージが出ることがある

AN HTTPD ゲストブック/コメント集(2002年3月15日17:15)

中田昭雄 nakata@st.rim.or.jp 2002/03/22 20:26

kuma007 さん、
いやそれはまだ対応できていません。
対応したときには、それらしいことが todo.html に載ると思いますので、それまで待ってください。

kuma007 kuma007@hotmail.com 2002/03/22 16:09

AnHttpd便利に使わせて頂いてます。
大量の同じ警告がログに出る件、
"(39d) Proxy　サーバの応答待ちタイムアウトx3に"
で対応したのかなと思ったのですが、今日39eで再発致しました。

#ちょっと気づくのが遅れてログが150MByteを超えました．．．
#ログの最大サイズが指定出来たらと思います。
#月・日と同じ感じで指定サイズ超で別ファイルにするオプションとか．．．

--errors.log-- 
Fri Mar 22 12:14:15 2002 Proxy: ClientSocket[0] select returns 1, supposed to be client abort 
Fri Mar 22 12:20:14 2002 Warning: Connection reset in clientWrite() (Thread 0) 
Fri Mar 22 12:20:20 2002 Warning: Connection reset in clientWrite() (Thread 0) 
Fri Mar 22 12:21:53 2002 Proxy: nSocket[3] error 6 
Fri Mar 22 12:21:53 2002 Warning: accept failed (code = 6), continue... 
Fri Mar 22 12:21:53 2002 Warning: select failed (code = 6), socket = 84, iProxy = 1000, continue... 
Fri Mar 22 12:21:53 2002 Warning: select failed (code = 6), socket = 84, iProxy = 1000, continue... 
 
．．．13万行目 
Fri Mar 22 12:24:51 2002 Warning: select failed (code = 6), socket = 84, iProxy = 1000, continue... 
Fri Mar 22 12:24:51 2002 Warning: select failed (code = 87), socket = 84, iProxy = 1000, continue... 
 
．．．163万行目 
Fri Mar 22 14:40:34 2002 Warning: select failed (code = 87), socket = 84, iProxy = 1000, continue... 
Fri Mar 22 14:40:34 2002 Warning: select failed (code = 6), socket = 84, iProxy = 1000, continue... 
Fri Mar 22 14:40:34 2002 AN HTTPD 1.39e Closed. 
Fri Mar 22 14:42:10 2002 AN HTTPD 1.39e Ready. 
↑ 
誰かがPCを再起動したらしい。 
 
---proxy.log(defaultのhttpd.logとは別名にしてます)--- 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/chair-s.gif HTTP/1.0"  200 5616 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/cactus-s.gif HTTP/1.0"  200 5510 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/shopping-s.gif HTTP/1.0"  200 5935 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/news-s.gif HTTP/1.0"  200 5292 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/birthday-s.gif HTTP/1.0"  200 5869 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/bottle-s.gif HTTP/1.0"  200 5229 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/chocolate-s.gif HTTP/1.0"  200 5483 
[22/Mar/2002:12:21:50 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/bee-s.gif HTTP/1.0"  200 5739 
[22/Mar/2002:12:21:51 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/camera-s.gif HTTP/1.0"  200 5780 
[22/Mar/2002:12:21:51 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/rockinghorse-s.gif HTTP/1.0"  200 4997 
[22/Mar/2002:12:21:51 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/bell-s.gif HTTP/1.0"  200 5616 
[22/Mar/2002:12:21:51 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/snowman-s.gif HTTP/1.0"  200 5049 
[22/Mar/2002:12:21:51 +0900] "GET http://www5d.biglobe.ne.jp/~m-uet/images/crown-s.gif HTTP/1.0"   0

中田昭雄 nakata@st.rim.or.jp 2002/03/16 07:29

kuma007 さん、
お知らせありがとうございます。
302 応答あたりで失敗しているようにも思えますが、もう少しよく調べて対策をとります。

kuma007 kuma007@hotmail.com 2002/03/15 17:15

AN HTTPDのVer.1.39cをWindowsMe上で主にPROXYサーバとして使用しています。

※別のPCからAN HTTPDを通して外部に接続しています。
突然PROXYが無反応になりERRORS.LOGに大量の同じエラーが書き出されるという現象が発生しました。
Ver1.39の時にも同じ現象が1度発生しましたが、今日Ver.1.39cで再発したので報告します。

Fri Mar 15 12:34:23 2002 Proxy: Can't connect the server (socket error 10038) 
Fri Mar 15 12:34:24 2002 Warning: Socket error 10038 in clientWrite() (Thread 2) 
Fri Mar 15 12:34:25 2002 Proxy: nSocket[3] select error 10004 while waiting for server response 
Fri Mar 15 12:34:38 2002 Warning: Socket error 10038 in clientWrite() (Thread 2) 
Fri Mar 15 12:34:41 2002 Warning: Socket error 10038 in clientWrite() (Thread 1) 
Fri Mar 15 12:34:48 2002 going to select to recieve more... 
Fri Mar 15 12:34:49 2002 Can't Create File in CheckFileLocation 
Fri Mar 15 12:34:50 2002 Can't Create File in CheckFileLocation 
Fri Mar 15 12:35:12 2002 Warning: Connection reset in clientWrite() (Thread 2) 
Fri Mar 15 12:35:53 2002 Warning: select() failed when Recv() in thread 1(ID=-4010237, socket=88), errono = 10004 
Fri Mar 15 12:36:21 2002 Warning: Connection reset in clientWrite() (Thread 4) 
Fri Mar 15 12:36:21 2002 Warning: Connection reset in clientWrite() (Thread 3) 
Fri Mar 15 12:36:21 2002 Proxy: Can't open socket:  (socket error 87) 
Fri Mar 15 12:36:23 2002 Warning: accept failed (code = 1), continue... 
Fri Mar 15 12:36:23 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:36:23 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:36:23 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
 
～省略：同じ警告が約20万行続く(1秒間に886行のペースで出力されてました)～ 
 
Fri Mar 15 12:40:26 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:40:26 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:40:26 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:40:26 2002 Warning: select failed (code = 1), socket = 84, iProxy = 1000, continue... 
Fri Mar 15 12:40:32 2002 AN HTTPD 1.39c Ready. 
↑ 
ここでAN HTTPDメニューの"終了(X)を実行。ページエラー発生。Closeは記録無しでした。

このときPROXYのLOGを見ると、

[15/Mar/2002:12:36:14 +0900] "GET http://images.google.co.jp/images?q=tbn:snLy9T3uKbYC:www.success-corp.co.jp/software/ps/tony/p-kareem.jpg HTTP/1.0"  200 1970 
[15/Mar/2002:12:36:15 +0900] "GET http://images.google.co.jp/images?q=tbn:7i-mq2Pk4z8C:ww21.tiki.ne.jp/~marble-choco/tailseelheart.jpg HTTP/1.0"  200 3959 
[15/Mar/2002:12:36:16 +0900] "GET http://images.google.co.jp/images?q=tbn:jmLuGGjbAXoC:www.rakuten.co.jp/starkids/img105364488.jpeg HTTP/1.0"  200 2141 
[15/Mar/2002:12:36:16 +0900] "GET http://images.google.co.jp/images?q=tbn:zlqlFxJ3amwC:www.tku.ac.jp/~kikaku/01natu8.JPG HTTP/1.0"  200 4520 
[15/Mar/2002:12:36:16 +0900] "GET http://images.google.co.jp/images?q=tbn:AQ6Y96Ak19YC:www.shikibo.co.jp/rrr/image/lett3.gif HTTP/1.0"  200 4127 
[15/Mar/2002:12:36:16 +0900] "GET http://images.google.co.jp/images?q=tbn:CognQlgsDFcC:www.enoshima-aquarium.co.jp/annai/eisui/hamuhamu/c_sinamon.jpg HTTP/1.0"  200 2864 
[15/Mar/2002:12:36:20 +0900] "GET http://images.google.co.jp/imgres?imgurl=www1.ocn.ne.jp/~sightgo/gazo/a-11.gif&imgrefurl=http://www1.ocn.ne.jp/~sightgo/newpage50.htm&h=100&w=67&prev=/images%3Fq%3D%2583E%2583H%2583z%2581%255B%2583%258B%2B%2583L%2583%2583%2583%2593%2583x%2583%258B%2583X%2581%255B%2583v%26svnum%3D10%26hl%3Dja%26ie%3DShift_JIS%26oe%3DShift_JIS%26sa%3DN HTTP/1.0"  200 937 
[15/Mar/2002:12:36:21 +0900] "GET http://ad.jp.ap.valuecommerce.com/servlet/jsbanner?sid=26583&pid=870065227 HTTP/1.0"  200 66 
[15/Mar/2002:12:36:21 +0900] "GET http://ad.jp.ap.valuecommerce.com/servlet/jsbanner?sid=26583&pid=870065176 HTTP/1.0"  200 66 
[15/Mar/2002:12:36:21 +0900] "GET http://ad.jp.ap.valuecommerce.com/servlet/htmlbanner?sid=26583&pid=870065176 HTTP/1.0"  200 524 
[15/Mar/2002:12:36:19 +0900] "GET http://travel.rakuten.co.jp/search/rList.jhtml?l2=1002&dType=1&lastPage=/search/searchArea.jhtml&_requestid=91120 HTTP/1.0"  200 113775 
[15/Mar/2002:12:36:21 +0900] "GET http://at.dcrew.com/adserver.exe/SITE=11093/AREA=1109311 HTTP/1.0"  302 261 
[15/Mar/2002:12:36:21 +0900] "GET http://ad011.naturalidentity.com/htview01.php3?hid=h112000090080 HTTP/1.0"  200 2763 
[15/Mar/2002:12:36:21 +0900] "GET http://images.google.co.jp/imgres?imgurl=www1.ocn.ne.jp/~sightgo/gazo/a-11.gif&imgrefurl=http://www1.ocn.ne.jp/~sightgo/newpage50.htm&h=100&w=67&prev=/images%3Fq%3D%2583E%2583H%2583z%2581%255B%2583%258B%2B%2583L%2583%2583%2583%2593%2583x%2583%258B%2583X%2581%255B%2583v%26svnum%3D10%26hl%3Dja%26ie%3DShift_JIS%26oe%3DShift_JIS%26sa%3DN&frame=small HTTP/1.0"  200 1597

でした。