IIS6 Problemas de registro de tempo de log

Question

Em três ocasiões separadas em dois servidores separados quase ao mesmo tempo, 6,9 horas aparentemente passaram sem nenhum dado ser gravado nos logs do IIS, mas, em uma inspeção mais próxima, parece que foi todos registrados de uma só vez.

Aqui estão os fatos como eu os conheço:

• Windows Server 2003 R2 com IIS6
• Registro usando GMT, horário local do servidor GMT-7.
• O aplicativo ainda estava em operação e eu tenho dados SQL para provar que
• Os intervalos de tempo aparecem no arquivo de log, não em dois

• cabeçalhos aparecem no intervalo

• O balanceador de carga toca a cada 30 segundos
• Sem cache

Veja as informações sobre um caso específico:

• aparece uma entrada para 2009-09-21 18:09:27
• então #headers
• a próxima entrada é para 2009-09-22 01:21:54, assim como as próximas 1600 entradas neste arquivo de log e 370 no próximo arquivo de log.
• cerca de metade das inscrições de ~ 2000 em 2009-09-22 01:21:54 são pings de balanceador de carga (estima-se 2 / min para 6,9hrs = 828 pings)
• as entradas são gravadas normalmente.

Acredito que esses eventos podem coincidir comigo ao implantar uma atualização do aplicativo ASP.NET nessas máquinas.

Veja alguns conteúdos relevantes dos registros em questão:

ex090921.log da linha 3684

2009-09-21 17:54:40 GET /ping.aspx - 80 404 0 0 3733 122 0
2009-09-21 17:55:11 GET /ping.aspx - 80 404 0 0 3733 122 0
2009-09-21 17:55:42 GET /ping.aspx - 80 404 0 0 3733 122 0
2009-09-21 17:56:13 GET /ping.aspx - 80 404 0 0 3733 122 0
2009-09-21 17:56:45 GET /ping.aspx - 80 404 0 0 3733 122 0
#Software: Microsoft Internet Information Services 6.0
#Version: 1.0
#Date: 2009-09-21 18:04:37
#Fields: date time cs-method cs-uri-stem cs-uri-query s-port sc-status sc-substatus sc-win32-status sc-bytes cs-bytes time-taken 
2009-09-22 01:04:06 GET /ping.aspx - 80 404 0 0 3733 122 3078
2009-09-22 01:04:06 GET /ping.aspx - 80 404 0 0 3733 122 109
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 278 122 3828
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 278 122 0
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 278 122 0
... continues until line 5449
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 277 122 0
<eof>

ex090922.log

#Software: Microsoft Internet Information Services 6.0
#Version: 1.0
#Date: 2009-09-22 00:00:16
#Fields: date time cs-method cs-uri-stem cs-uri-query s-port sc-status sc-substatus sc-win32-status sc-bytes cs-bytes time-taken 
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 277 122 0
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 277 122 0
... continues until line 367
2009-09-22 01:04:06 GET /ping.aspx - 80 200 0 0 277 122 0
2009-09-22 01:04:30 GET /ping.aspx - 80 200 0 0 277 122 0
... back to normal behavior

Observe a data / hora aparentemente correta gravada no #header do novo arquivo de log. Observe também que /ping.aspx retornou 404, em seguida, mudou para 200, assim que o problema foi iniciado. Eu renomeio a "Estou vivo página" para que o balanceador de carga pára de enviar solicitações para o servidor enquanto eu estou trabalhando nisso. O que você vê aqui é eu renomeá-lo para que o balanceador de carga use o servidor. Então, esse problema definitivamente coincide comigo reativar o servidor.

Alguma idéia?

    

Answer 1

A única coisa que sei que fará com que os cabeçalhos apareçam no meio do arquivo de log é o site sendo interrompido ou as configurações para o arquivo de log sendo alterado, como nesta seção do primeiro log:

2009-09-21 17:56:45 GET /ping.aspx - 80 404 0 0 3733 122 0
  #Software: Microsoft Internet Information Services 6.0
  #Version: 1.0#Date: 2009-09-21 18:04:37
  #Fields: date time cs-method cs-uri-stem cs-uri-query 
           s-port sc-status sc-substatus sc-win32-status 
           sc-bytes cs-bytes time-taken
2009-09-22 01:04:06 GET /ping.aspx - 80 404 0 0 3733 122 3078

Além disso, não estou entendendo o que você quer dizer em seus comentários depois das seções de log. Você pode explicar? O que você quer dizer com o problema coincide com a implantação do aplicativo e a renomeação do ping.aspx? Estou confuso, desculpe.