Há cerca de um ano, instalei um ESXi 5.5 para virtualizar um antigo servidor físico Windows 2000 que, infelizmente, não pode ser facilmente atualizado/migrado/substituído. O armazenamento de dados ESXi principal foi criado em cima de um array RAID5 construído em torno de discos SATA internos de 4x2 TB.
Depois de convertido, executo etapas comuns de higienização, incluindo a instalação de ferramentas VMware adequadas.
Logo após o W2K, outras VMs foram definidas no mesmo ESXi, incluindo uma VM CentOS que, assim como o W2K, funciona 24 horas por dia, 7 dias por semana, 365 dias por ano.
Configurei o "Inicialização/Desligamento da Máquina Virtual" para que todas as VMs precisem ser iniciadas manualmente, após a reinicialização do hipervisor.
Tudo está funcionando corretamente, com apenas uma exceção: se um corte de energia for sofrido pelo hipervisor (enquanto as VMs estão ligadas), enquanto a VM CentOS é "ligada" em segundos,a VM W2K leva mais de uma hora. Por favor, note que estounãofalando sobre o tempo que o Windows 2000 leva para concluir o processo de inicialização. Estou falando do tempo que o ESXi leva para inicializar a VM. Em detalhe:
tempo t0: o hipervisor é iniciado, após um corte de energia;
...esperando algum tempo apenas para ter certeza de que o ESXi concluiu corretamente seu próprio processo de inicialização...
tempo t1: clique com o botão direito em W2K VM => Power => Power On
...a mensagem "O nome da VM está iniciando" aparece na área de notificação, com a barra de progresso indo de 0 a 45% em cerca de 1 segundo...
...a mensagem "O nome da VM está iniciando" fica lá, fixada em 45%, por cerca de 80 (oitenta!) minutos, ou até mais...
...barra de progresso repentinamente passa de 45% para 100%...
...a mensagem "VM está ligada" aparece na área de notificação...
hora t2: o console da VM começa a mostrar 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 são gastos 80 minutos entre t1 e t2 (e não entre t2 e t3).
Aqui abaixo você pode encontrar alguns números reais, retirados do corte de energia de hoje (...e sim! Não há UPS protegendo tal servidor. Por favor, seja gentil e não peça detalhes!):
Como você mesmo pode ver:
- a energia voltou pouco antes das 17h21;
- O ESXi concluiu a inicialização por volta das 17h21;
- Fiz o “Power on” às 18:41:53;
- ESXi me disse que a VM foi ligada às 20:15:51;
- ao mesmo tempo (20:15:51) ESXi reclamou que as ferramentas VMware não estavam instaladas na VM
Quanto a este último ponto (faltam ferramentas VMware), elas estão devidamente instaladas, como você pode ver abaixo:
Observe que esse atraso énãoregistrado na VM CentOS: ele é ligado, pelo ESXi, alguns segundos após a solicitação "Power On".
Suspeitei que o ESXi realizaria uma espécie de "verificação de integridade" do sistema de arquivos que hospeda os dados da VM e como a VM W2K possui:
- disco1: 67 GB
- disco2: 1 GB
- disco3: 610 GB
- disco4: 360 GB
- disco5: 1,9 TB
provavelmente leva muito tempo para verificar tudo. Tentei verificar, via console ESXi, se havia algum processo "semelhante ao fsck" em execução, mas encontreinada. Também procurei por arquivos de log, mas... não consegui encontrar nada relevante.
Além disso: se o corte de energia ocorrer quando a VM estiver desligada, uma inicialização normal e rápida será registrada e todas as opções acima serãonãoaplicar.
Portanto, a questão é: alguém tem alguma ideia de qual é o problema e como posso resolvê-lo ou solucioná-lo?
Atualização 1:
Parece que o ESXi precisa de muito tempo para "abrir 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 registro, vejo detalhes de 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 foi preciso:
- 2 minutos para abrir DISK1 (67GB);
- 2 minutos para abrir DISK4 (360GB);
- 24 minutos para abrir DISK3 (610GB);
- 66 minutos para abrir DISK5 (1,9TB);
Eu me pergunto por que DISK3 e DISK5 consumiam tanto tempo.
Quanto aos instantâneos, aqui abaixo você pode ver o status atual:
