IIS - AppPool의 "Disable Overlapped Recycle" 옵션의 부작용
지난 글에 설명한,
IIS - AppPool의 "Disable Overlapped Recycle" 옵션
; https://www.sysnet.pe.kr/2/0/13514
"Disable Overlapped Recycle" 옵션은 특정 상황에서 원치 않는 결과를 가져오기도 합니다. 이러한 좋은 예로, Web Application에서 별도의 TCP 서비스를 하나 추가해서 열어놨다고 가정해 보겠습니다.
아마도 이런 식으로 구현할 수 있을 것입니다.
// 닷넷 프레임워크 기준 예제
// ...[생략]...
namespace WebApplication2
{
public class WebApiApplication : System.Web.HttpApplication
{
static TcpListener _tcpListener = null;
protected void Application_Start()
{
Log("Application_Start (Start)");
AreaRegistration.RegisterAllAreas();
GlobalConfiguration.Configure(WebApiConfig.Register);
FilterConfig.RegisterGlobalFilters(GlobalFilters.Filters);
RouteConfig.RegisterRoutes(RouteTable.Routes);
BundleConfig.RegisterBundles(BundleTable.Bundles);
if (_tcpListener == null)
{
_tcpListener = new TcpListener(IPAddress.Any, 18002);
_tcpListener.Start();
}
Log("Application_Start (End)");
}
protected virtual void Application_End()
{
if (_tcpListener != null)
{
_tcpListener.Stop();
_tcpListener = null;
}
Log("Application_End");
}
protected virtual void Application_BeginRequest()
{
Log("Application_BeginRequest: " + HttpContext.Current.Request.RawUrl);
}
public static void Log(string message)
{
System.Diagnostics.Trace.WriteLine($"[{DateTime.Now}, {ValuesController.ProcessId}] WebApplication2 - {message}");
}
}
}
"
IIS - AppPool의 "Disable Overlapped Recycle" 옵션" 글의 내용을 이해하셨다면 대충 감이 오실 텐데요, 실제로 위의 코드로 로그를 남기는 상태에서 Recycle을 해보면 다음과 같은 상황을 확인할 수 있습니다.
[6628] [2024-01-05 오후 10:11:31, 6628] WebApplication2 - Application_Start (Start)
[6628] [2024-01-05 오후 10:11:31, 6628] WebApplication2 - Application_Start (End)
[6628] [2024-01-05 오후 10:11:31, 6628] WebApplication2 - Application_BeginRequest: /
[6628] [2024-01-05 오후 10:11:31, 6628] WebApplication2 - Application_BeginRequest: /api/Values
[1740] [2024-01-05 오후 10:12:03, 1740] WebApplication2 - Application_Start (Start)
[6628] [2024-01-05 오후 10:12:04, 6628] WebApplication2 - Application_End
[1740] [2024-01-05 오후 10:12:13, 1740] WebApplication2 - Application_End
6628 w3wp.exe가 뜬 상태에서 Recycle을 시도했더니 새롭게 1740 프로세스가 떠서 Application_Start 이벤트까지 진입했습니다. 하지만, 아직 6628 프로세스에서 열고 있던 TCP 서버가 종료되지 않았으므로 _tcpListener.Start(); 코드에서 예외가 발생하게 됩니다.
이 당시의 이벤트 로그를 보면 이런 항목을 보게 될 것입니다.
Exception information:
Exception type: HttpException
Exception message: Only one usage of each socket address (protocol/network address/port) is normally permitted
at System.Web.HttpApplicationFactory.EnsureAppStartCalledForIntegratedMode(HttpContext context, HttpApplication app)
at System.Web.HttpApplication.RegisterEventSubscriptionsWithIIS(IntPtr appContext, HttpContext context, MethodInfo[] handlers)
at System.Web.HttpApplication.InitSpecial(HttpApplicationState state, MethodInfo[] handlers, IntPtr appContext, HttpContext context)
at System.Web.HttpApplicationFactory.GetSpecialApplicationInstance(IntPtr appContext, HttpContext context)
at System.Web.Hosting.PipelineRuntime.InitializeApplication(IntPtr appContext)
...[생략]...
비록 예외가 발생했지만, 새롭게 뜬 w3wp.exe는 비정상 종료까지는 안 합니다. 대신 예외가 발생한 Application 인스턴스의 생성은 실패하고 Application_End 이벤트 단계로 곧바로 진행해 버립니다.
그 사이, 기존의 w3wp.exe는 새로운 프로세스가 비록 Application 개체 생성에 실패했지만 더 이상의 Recycle 단계를 미루지 않고 마찬가지로 Application_End 이벤트 단계를 실행하며 종료합니다. 즉, 이때가 되어서야 (신규 프로세스의 소켓 사용을 방해했던) TCP Listener는 자원을 해제하게 되는 것입니다.
결과적으로, 새롭게 뜬 w3wp.exe는 전체적인 초기화를 ASP.NET 런타임의 초기 부분만 진행하는 선에서 끝이 납니다. 다행인 것은, 이후 요청(예를 들어 /Home)이 들어오면 다시 새롭게 ASP.NET Pipeline을 구축하려고 시도하고, 이 과정에서 다음의 이벤트들이 찍히는 것을 볼 수 있습니다.
[1740] [2024-01-05 오후 10:12:29, 1740] WebApplication2 - Application_Start (Start)
[1740] [2024-01-05 오후 10:12:29, 1740] WebApplication2 - Application_Start (End)
[1740] [2024-01-05 오후 10:12:29, 1740] WebApplication2 - Application_BeginRequest: /Home
여기서 재미있는 것은,
preloadEnabled로 인해 발생했어야 할 "/" 요청과 "initializationPage"로 설정해 둔 페이지에 대한 방문 기회가 없어진다는 점입니다. 파이프라인 구축에 실패한 마당에 거기까지 바라는 것은 좀 무리겠죠. ^^
만약 이런 부작용을 원치 않는다면 1) "Disable Overlapped Recycle" 옵션을 true로 바꾸거나, 2) TCP Listener의 생성을 Application_Start 시점보다 미뤄야 합니다.
1번을 선택한다면, IIS는 Recycle 시 현재 서비스 중인 w3wp.exe를 확실히 종료시킨 다음에야 새로운 w3wp.exe를 실행시키는 식으로 동작하기 때문에 TCP 포트 충돌의 위험이 없어집니다.
비록 1번 조치는 간단하지만, 이로 인해 초기 요청에 대한 응답 지연 현상이 다시 부각될 수밖에 없습니다. 따라서, 1번은 임시 조치로만 대응하고, 천천히 시간을 들여 약간 귀찮더라도 2번 방법의 코드 수정을 하는 것이 좋습니다.
참고로, 최악의 조치는 예외를 잡는 것입니다.
protected void Application_Start()
{
...[생략]...
try
{
if (_tcpListener == null)
{
_tcpListener = new TcpListener(IPAddress.Any, 18002);
_tcpListener.Start();
}
} catch { }
}
만약 저렇게 했다면 _tcpListener는 다시 초기화를 할 수 있는 기회마저 잃어버리게 되고, 아마도 서버 관리자들한테서 "iisreset"을 해야만 동작한다던가, 작업 관리자에서 강제 종료를 한 경우에만 동작이 잘 되더라는... 이상한 피드백을 받게 될 것입니다.
[이 글에 대해서 여러분들과 의견을 공유하고 싶습니다. 틀리거나 미흡한 부분 또는 의문 사항이 있으시면 언제든 댓글 남겨주십시오.]