🔍

IISのエラーログ「Request_Cancelled」を調べた結果

2022/12/30 に公開

Windows

稼働中のWebサービスからエラー通知が来ていたため、その原因を調査した。稼働中のWebサービスはWindowsサーバーのため、WebサーバーはIISを利用していた。
私はこれまでNginxしか触ったことがなかったため、ログの場所や意味なども調べながら調査した。その辺りも含めて本記事にメモとして残す。

linuxで当たり前に使っているcatやlessコマンドは、DOSでは使えなかったためこちらも調べた。typeコマンドやmoreコマンドによりファイルの内容を表示できた。
よく使ったコマンド: type ファイル名 | more

<a href="https://learn.microsoft.com/ja-jp/windows-server/administration/windows-commands/type" style="" target="_blank" rel="nofollow noopener noreferrer">https://learn.microsoft.com/ja-jp/windows-server/administration/windows-commands/type</a> <iframe id="zenn-embedded__156a26c91f8" src="https://embed.zenn.studio/card#zenn-embedded__156a26c91f8" data-content="https%3A%2F%2Fwindows.command-ref.com%2Fcmd-more.html" frameborder="0" scrolling="no" loading="lazy"/><a href="https://windows.command-ref.com/cmd-more.html" style="" target="_blank" rel="nofollow noopener noreferrer">https://windows.command-ref.com/cmd-more.html</a> <a class="header-anchor-link" href="#iis%E3%81%AE%E3%83%AD%E3%82%B0" aria-hidden="true"/> IISのログ</h3> <li>IISにおいて必要となる、基本的なログ情報 個人的にはアクセスログ、エラーログ、ログの設定ファイルの三つを押さえれば問題なしと思った。 <a href="https://jpdsi.github.io/blog/web-apps/LogCollection1/" target="_blank" rel="nofollow noopener noreferrer">https://jpdsi.github.io/blog/web-apps/LogCollection1/</a> <a class="header-anchor-link" href="#%E3%82%A2%E3%82%AF%E3%82%BB%E3%82%B9%E3%83%AD%E3%82%B0" aria-hidden="true"/> アクセスログ</h3> %SYSTEMDRIVE%\inetpub\logs\LogFiles <a href="https://www.keicode.com/iis/iis-access-log.php" target="_blank" rel="nofollow noopener noreferrer">https://www.keicode.com/iis/iis-access-log.php</a> sc-statusがエラーコードとなっていれば、エラーログにも記録されているはず。 <a href="https://learn.microsoft.com/ja-jp/windows/win32/http/w3c-logging" target="_blank" rel="nofollow noopener noreferrer">https://learn.microsoft.com/ja-jp/windows/win32/http/w3c-logging</a> <div class="code-block-filename-container">アクセスログの例</div> <pre class="language-log"><code class="language-log">#Fields: date time c-ip cs-username s-ip s-port cs-method cs-uri-stem cs-uri-query sc-status cs(User-Agent) 2002-05-02 17:42:15 172.22.255.255 - 172.30.255.255 80 GET /images/picture.jpg - 200 Mozilla/4.0+(compatible;MSIE+5.5;+Windows+2000+Server) </div>なおsc-win32-status(windowsステータスコード)は、0であれば正常。その他はエラーのよう。 <iframe id="zenn-embedded__9a59c22805e31" src="https://embed.zenn.studio/card#zenn-embedded__9a59c22805e31" data-content="https%3A%2F%2Flearn.microsoft.com%2Fja-jp%2Fwindows%2Fwin32%2Fdebug%2Fsystem-error-codes--0-499-" frameborder="0" scrolling="no" loading="lazy"/><a href="https://learn.microsoft.com/ja-jp/windows/win32/debug/system-error-codes--0-499-" style="" target="_blank" rel="nofollow noopener noreferrer">https://learn.microsoft.com/ja-jp/windows/win32/debug/system-error-codes--0-499-</a> 64のエラーケースの事例。 サーバーがデータを送ったのに、クライアントとの接続が切れたなどの理由からクライアントの応答がなかったからということだそうです。 <a href="https://social.msdn.microsoft.com/Forums/ja-JP/c10ef91b-cb32-4134-849f-aba9193e6e5b/iis12398125251246412395scwin32status12392123751239064123981251312483?forum=iis7ja" target="_blank" rel="nofollow noopener noreferrer">https://social.msdn.microsoft.com/Forums/ja-JP/c10ef91b-cb32-4134-849f-aba9193e6e5b/iis12398125251246412395scwin32status12392123751239064123981251312483?forum=iis7ja</a> <a class="header-anchor-link" href="#%E3%82%A8%E3%83%A9%E3%83%BC%E3%83%AD%E3%82%B0" aria-hidden="true"/> エラーログ</h3> C:\Windows\System32\LogFiles\HTTPERR\httperr1.log <a href="https://blog.putise.com/iis%E3%81%AE%E3%82%A8%E3%83%A9%E3%83%BC%E3%83%AD%E3%82%B0%E3%81%AF%E3%81%A9%E3%81%93%E3%81%AB%E3%81%82%E3%82%8B%E3%81%AE%E3%81%8B%EF%BC%9F%E3%82%A8%E3%83%A9%E3%83%BC%E3%83%AD%E3%82%B0%E3%81%AE/#IIS" target="_blank" rel="nofollow noopener noreferrer">https://blog.putise.com/iisのエラーログはどこにあるのか？エラーログの/#IIS</a> 下記を参照すれば問題なくおえるはず。 <a href="https://learn.microsoft.com/ja-jp/troubleshoot/developer/webapps/aspnet/site-behavior-performance/error-logging-http-apis#configure-http-api-error-logging" target="_blank" rel="nofollow noopener noreferrer">https://learn.microsoft.com/ja-jp/troubleshoot/developer/webapps/aspnet/site-behavior-performance/error-logging-http-apis#configure-http-api-error-logging</a> <div class="code-block-filename-container">エラーログの例</div> <pre class="language-log"><code class="language-log">2002-07-05 18:45:09 172.31.77.6 2094 172.31.77.6 80 HTTP/1.1 GET /qos/1kbfile.txt 503 - ConnLimit 2002-07-05 19:51:59 127.0.0.1 2780 127.0.0.1 80 HTTP/1.1 GET /ThisIsMyUrl.htm 400 - Hostname 2002-07-05 19:53:00 127.0.0.1 2894 127.0.0.1 80 HTTP/2.0 GET / 505 - Version_N/S 2002-07-05 20:06:01 172.31.77.6 64388 127.0.0.1 80 - - - - - Timer_MinBytesPerSecond <a class="header-anchor-link" href="#%E3%82%A8%E3%83%A9%E3%83%BC%E3%83%AD%E3%82%B0%E3%80%8Crequest_cancelled%E3%80%8D%E3%81%8B%E3%82%89%E8%AA%BF%E6%9F%BB%E3%81%97%E3%81%9F%E7%B5%90%E6%9E%9C" aria-hidden="true"/> エラーログ「Request_Cancelled」から調査した結果</h3> これらの記事が見つかった。データ送受信時の最大値や送受信の数が起因となってエラーが生じるよう。 <iframe id="zenn-embedded__0fb1fd0104bcb" src="https://embed.zenn.studio/card#zenn-embedded__0fb1fd0104bcb" data-content="https%3A%2F%2Fstackoverflow.com%2Fquestions%2F27725258%2Fhttperr-log-request-cancelled-while-troubleshooting-wcf-service" frameborder="0" scrolling="no" loading="lazy"/><a href="https://stackoverflow.com/questions/27725258/httperr-log-request-cancelled-while-troubleshooting-wcf-service" style="" target="_blank" rel="nofollow noopener noreferrer">https://stackoverflow.com/questions/27725258/httperr-log-request-cancelled-while-troubleshooting-wcf-service</a> <iframe id="zenn-embedded__98ad521a8a4f8" src="https://embed.zenn.studio/card#zenn-embedded__98ad521a8a4f8" data-content="https%3A%2F%2Fwww.appsloveworld.com%2Fcsharp%2F100%2F1735%2Fgetting-request-cancelled-in-windir-system32-logfiles-httperr-from-ios-device" frameborder="0" scrolling="no" loading="lazy"/><a href="https://www.appsloveworld.com/csharp/100/1735/getting-request-cancelled-in-windir-system32-logfiles-httperr-from-ios-device" style="" target="_blank" rel="nofollow noopener noreferrer">https://www.appsloveworld.com/csharp/100/1735/getting-request-cancelled-in-windir-system32-logfiles-httperr-from-ios-device</a> システム全体の設定ファイル: ApplicationHost?.config <a href="https://techinfoofmicrosofttech.osscons.jp/index.php?IIS%20config#vd6fb25c" target="_blank" rel="nofollow noopener noreferrer">https://techinfoofmicrosofttech.osscons.jp/index.php?IIS config#vd6fb25c</a> アプリケーション固有の設定値: Web.config <a href="https://techinfoofmicrosofttech.osscons.jp/index.php?.NET%20config#u3d1af27" target="_blank" rel="nofollow noopener noreferrer">https://techinfoofmicrosofttech.osscons.jp/index.php?.NET config#u3d1af27</a> 上記ファイルを確認したが、問題なかった。 改めてエラーログの発生日時を確認すると、29時間おきに記録されていることがわかった。 <li>IISにおけるアプリケーションプールのリサイクルが原因となり「Request_Cancelled」というエラーログが生じていたと考えられる。 アプリケーションプールのリサイクルはデフォルト設定（29時間間隔）となっており、エラーが生じていたのは必ずそのタイミングであったため。</li> <li>アプリケーションプールを定期的にリサイクルする目的は、下記。</li> 問題のあるアプリケーションがあり、問題の原因となっているコードを簡単に修正できない場合は、アプリケーションにサービスを提供するワーカープロセスを定期的にリサイクルすることで、これらの問題の範囲を制限できます。