ESXi 5.5: mais de uma hora para ligar uma VM Win2K

Question

Cerca de um ano atrás eu instalei um ESXi 5.5 para virtualizar um antigo servidor físico do Windows 2000 que, infelizmente, não pode ser facilmente atualizado / migrado / substituído. O datastore ESXi principal foi criado no topo de um array RAID5 construído em torno de discos SATA internos 4x2TB.

Uma vez convertida, executo etapas comuns de sanitização, incluindo a instalação de ferramentas adequadas do VMware.

Logo após o W2K, outras duas VMs foram definidas no mesmo ESXi, incluindo uma VM do CentOS que, como para o W2K, está rodando 24/7/365.

Eu configurei o "Virtual Machine Startup / Shutdown" para que todas as VMs precisem ser iniciadas manualmente, após uma reinicialização do hipervisor.

Tudo está sendo executado corretamente, com apenas uma exceção: se um corte de energia for sofrido pelo hipervisor (enquanto as VMs estiverem ligadas), enquanto a VM do CentOS estiver "ligada" em segundos, a VM do W2K leva mais de uma hora . Por favor, note que eu estou não falando sobre o tempo exigido pelo Windows 2000 para concluir o processo de inicialização. Estou falando sobre o tempo exigido pelo ESXi para iniciar a VM. Em detalhe:

• tempo t0: o hipervisor é iniciado após um corte de energia;

  ... aguardando algum tempo apenas para ter certeza de que o ESXi concluiu adequadamente seu próprio processo de inicialização ...

• tempo t1: clique com o botão direito do mouse em W2K VM = > Potência = > Ligar

  ... a mensagem "nome da VM está iniciando" aparece na área de notificação, com a barra de progresso passando de 0 a 45% em cerca de 1 segundo ...

  ... a mensagem "VM name is starting" permanece aí, fixada em 45%, por cerca de 80 (oitenta!) minutos, ou até mais ...

  ... a barra de progresso vai de repente de 45% para 100% ...

  ... a mensagem "VM is powered on" aparece na área de notificação ...

• tempo t2: o início do console da VM mostrando que o W2K iniciou o processo de inicialização

  ... dois / três minutos são necessários para o W2K completar seu próprio processo de inicialização ...

• tempo t3: os serviços W2K estão disponíveis na rede.

Deixe-me insistir em dizer que 80 minutos são gastos entre t1 e t2 (e não entre t2 e t3).

Aqui abaixo você pode encontrar um número real, retirado do power-cut de hoje (... e sim! Não há UPS protegendo esse servidor. Por favor, seja gentil e não peça detalhes!):

Comovocêpodeverporsimesmo:

• opodervoltoulogoantesdas17:21;
• OESXiconcluiuainicializaçãoemtornode17:21;
• eufizo"Power on" às 18:41:53;
• O ESXi me disse que a VM estava ligada às 20:15:51;
• ao mesmo tempo (20:15:51) O ESXi reclamou que as ferramentas do VMware não estavam instaladas na VM

Quanto a este último ponto (falta de ferramentas VMware), eles estão instalados corretamente, como você pode ver abaixo:

Porfavor,notequetalatrasonãoénãoregistradonaVMdoCentOS:eleéativadopeloESXidentrodealgunssegundosdarequisição"Power On".

Eu suspeitava que o ESXi executaria uma espécie de "verificação de integridade" do sistema de arquivos que hospeda os dados da VM e como a VM do W2K possui:

• disk1: 67 GB
• disk2: 1 GB
• disk3: 610 GB
• disk4: 360 GB
• disk5: 1,9TB

provavelmente leva muito tempo para verificar tudo. Eu tentei verificar, via console ESXi, se havia algum processo "fsck-like" em execução, mas encontrei nada . Também procurei por arquivos de log, mas ... não consegui encontrar nada relevante.

Além disso: se o corte de energia acontece quando a VM está desligada, uma power-on normal e rápida é registrada e todas as opções acima não são aplicadas.

Portanto, a pergunta é: alguém tem uma pista sobre qual é o problema e como posso resolvê-lo ou resolvê-lo?

Atualização 1:

Parece que o ESXi precisa de muito tempo "abrindo discos". Acabei de encontrar isso:

~ # grep took /vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/vmware.log
2015-08-21T18:15:51.282Z| vmx| I120: DISK: Opening disks took 5636937 ms.

e 5636937 ms são exatamente 93 minutos!

Logo acima dessa linha de log eu vejo detalhes para cada um dos 5 discos ( linhas começando com # foram adicionadas por mim, para esclarecimento ):

# server_2k_COMPANY_1-000003.vmdk => DISK 2 - 1 GB
2015-08-21T16:41:54.613Z| Worker#1| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_1-000003.vmdk" (flags 0xa, type vmfs).
2015-08-21T16:41:54.613Z| Worker#1| I120: DISK: Disk '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_1-000003.vmdk' has UUID '60 00 c2 93 75 e2 fd 3d-40 43 f8 25 f0 5a b8 9b'
2015-08-21T16:41:54.613Z| Worker#1| I120: DISK: OPEN '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_1-000003.vmdk' Geo (517/128/32) BIOS Geo (0/0/0)
#
# server_2k_COMPANY-000003.vmdk => DISK 1 - 67 GB
2015-08-21T16:43:40.637Z| Worker#0| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY-000003.vmdk" (flags 0xa, type vmfs).
2015-08-21T16:43:40.637Z| Worker#0| I120: DISK: Disk '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY-000003.vmdk' has UUID '60 00 c2 9a 0a c0 10 79-6e 68 52 0d 0d 7a 23 cb'
2015-08-21T16:43:40.637Z| Worker#0| I120: DISK: OPEN '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY-000003.vmdk' Geo (8844/255/63) BIOS Geo (0/0/0)
#
# server_2k_COMPANY_3-000003.vmdk => DISK 4 - 360 GB
2015-08-21T16:45:28.355Z| Worker#3| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_3-000003.vmdk" (flags 0xa, type vmfs).
2015-08-21T16:45:28.355Z| Worker#3| I120: DISK: Disk '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_3-000003.vmdk' has UUID '60 00 c2 95 13 e3 ad 8d-62 5f 94 12 52 53 de 82'
2015-08-21T16:45:28.355Z| Worker#3| I120: DISK: OPEN '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_3-000003.vmdk' Geo (47834/255/63) BIOS Geo (0/0/0)
#
# server_2k_COMPANY_2-000003.vmdk => DISK 3 - 610GB
2015-08-21T17:09:00.957Z| Worker#2| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_2-000003.vmdk" (flags 0xa, type vmfs).
2015-08-21T17:09:00.957Z| Worker#2| I120: DISK: Disk '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_2-000003.vmdk' has UUID '60 00 c2 9d 4d d2 57 e6-da 9e 1b 8c 54 ab 39 62'
2015-08-21T17:09:00.957Z| Worker#2| I120: DISK: OPEN '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_2-000003.vmdk' Geo (79736/255/63) BIOS Geo (0/0/0)
#
# server_2k_COMPANY_4-000001.vmdk => DISK 5 - 1,9 TB
2015-08-21T18:15:51.268Z| vmx| I120: DISKLIB-LIB   : Opened "/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_4-000001.vmdk" (flags 0xa, type vmfs).
2015-08-21T18:15:51.269Z| vmx| I120: DISK: Disk '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_4-000001.vmdk' has UUID '60 00 c2 91 b1 7d cd 92-c5 13 e7 aa fc 81 b1 33'
2015-08-21T18:15:51.269Z| vmx| I120: DISK: OPEN '/vmfs/volumes/54b687a6-e5de9210-27fe-549f35062eb4/server_2k_COMPANY/server_2k_COMPANY_4-000001.vmdk' Geo (253981/255/63) BIOS Geo (0/0/0)
#
2015-08-21T18:15:51.282Z| vmx| I120: DISK: Opening disks took 5636937 ms.

Então demorou:

• 2 minutos para abrir o DISK1 (67 GB);
• 2 minutos para abrir o DISK4 (360 GB);
• 24 minutos para abrir o DISK3 (610 GB);
• 66 minutos para abrir DISK5 (1,9TB);

Eu me pergunto por que DISK3 e DISK5 foram tão demorados.

Quanto aos instantâneos, aqui abaixo você pode ver o status atual: