Re: Base image capture failure

Fri, 05 Feb 2010 07:11:52 -0800

I'm glad we're gaining on it. The log output you included is very helpful. I see a few problems: 

Problem 1:
Install nmap. This is listed as a system requirement on the main VCL wiki page but was not listed on the management node instructions page. I have updated this page. If yum is available on your management node OS, try 'yum install nmap'. There are also rpms available at http://nmap.org/download.html. 


Problem 2:

The network interface name on the Windows VM is named vmnet0 so it was 
ignored.  The instructions on the page you mentioned refer to interfaces 
named vmnet* but the interfaces it's referring to are of the VM host 
server, not the VM guest.  I'm guessing you manually set the interface 
names from "Local Area Connection*" to vmnet* on the Windows VM.  Rename 
them back to "Local Area Connection*" to fix this problem.



VCL needs to figure out the public and private adapter names in order to 
run several configuration commands.  The code determines the private 
interface by matching its IP address with what's set in /etc/hosts.  The 
public interface is much more difficult because there could be multiple 
additional interfaces.  From the remaining interfaces, the code ignores 
interfaces if the name contains 'loopback|vmnet|afs|tunnel'.  It also 
ignores interfaces if the  description contains 
'loopback|virtual|afs|tunnel|pseudo|6to4|isatap'.  The interfaces named 
vmnet are ignored in case an image contains a VMware product with 
virtual interfaces which may or may not be using public addresses.



Problem 3:

Something appears to have unregistered your VM.  Run 'vmware-cmd -l' on 
the VMware host and check if the path to 15vmguest-1.vmx appears.  If 
not, it needs to be registered before attempting the image capture 
again.  You can do it by adding the VM via the VMware GUI or by running 
(all one line):
vmware-cmd -s register /var/lib/vmware/Virtual\ 
Machines/15vmguest-1/15vmguest-1.vmx


Then start the VM:

vmware-cmd /var/lib/vmware/Virtual\ Machines/15vmguest-1/15vmguest-1.vmx 
start


Hope this helps,
Andy


Terry McGuire wrote:

On 3 Feb 2010, at 1854h, Andy Kurth wrote:


Try this:
-Uninstall Cygwin.  There may not be an entry in Add/Remove Programs. If not, 
just delete C:\cygwin.
-Download and install setup-legacy.exe which installs Cygwin 1.5:
http://cygwin.com/setup-legacy.exe
-Follow the same VCL Cygwin installation instructions.


I'm pretty sure they don't pay you enough, Andy.  Once again, this did the 
trick.  Now, much more happens before it comes to a grinding halt, but grinding 
halt it continues to come to.  The initial warnings are these:



_________________
2010-02-04 
13:56:50|12426|1:15|image|Windows.pm:get_network_configuration(4630)|attempting 
to retrieve network configuration
2010-02-04 13:56:50|12426|1:15|image|utils.pm:run_ssh_command(6180)|executing 
SSH command on vmguest-1:
|12426|1:15|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p 22 -x vmguest-1 
'$SYSTEMROOT/System32/ipconfig.exe /all' 2>&1
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4634)|ran 
ipconfig
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4655)|found 
interface: vmnet0
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4655)|found 
interface: vmnet1
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4706)|saving 
network configuration in $self->{network_configuration}
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4710)|network 
configuration has already been retrieved
2010-02-04 
13:56:52|12426|1:15|image|DataStructure.pm:get_computer_private_ip_address(1442)|attempting
 to retrieve private IP address for computer: vmguest-1
2010-02-04 
13:56:52|12426|1:15|image|DataStructure.pm:get_computer_private_ip_address(1446)|retrieved
 contents of /etc/hosts on this management node, contains 7 lines
2010-02-04 
13:56:52|12426|1:15|image|DataStructure.pm:get_computer_private_ip_address(1479)|found
 line for 'vmguest-1' in /etc/hosts:
|12426|1:15|image| 192.168.1.1 vmguest-1
2010-02-04 
13:56:52|12426|1:15|image|DataStructure.pm:get_computer_private_ip_address(1490)|found
 IP address: 192.168.1.1
2010-02-04 13:56:52|12426|1:15|image|DataStructure.pm:_automethod(697)|data structure 
updated: $self->request_data->{reservation}{15}{computer}{privateIPaddress}
|12426|1:15|image| computer_private_ip_address = 192.168.1.1
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4760)|interface 
ignored because of name: vmnet0, description: VMware Accelerated AMD PCNet 
Adapter #2, address(es): 129.128.9.108
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4747)|private 
interface found: vmnet1, description: VMware Accelerated AMD PCNet Adapter, 
address(es): 192.168.1.1


|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4817)|unable to determine the public interface:

|12426|1:15|image| %
|12426|1:15|image| |--%{vmnet0}
|12426|1:15|image| |--{autoconfiguration_enabled} = 'Yes'
|12426|1:15|image| |--{connection_specific_dns_suffix} = 'aict.ualberta.ca'
|12426|1:15|image| |--{default_gateway} = '129.128.8.1'
|12426|1:15|image| |--{description} = 'VMware Accelerated AMD PCNet Adapter #2'
|12426|1:15|image| |--{dhcp_enabled} = 'Yes'
|12426|1:15|image| |--{dhcp_server} = '129.128.3.245'
|12426|1:15|image| |--{dns_server} = '129.128.76.233'
|12426|1:15|image| |--%{vmnet0}{ip_address}
|12426|1:15|image| |--{129.128.9.108} = '255.255.254.0'
|12426|1:15|image| |--{lease_expires} = 'Tuesday, February 09, 2010 11:03:36 AM'
|12426|1:15|image| |--{lease_obtained} = 'Thursday, February 04, 2010 11:03:36 
AM'
|12426|1:15|image| |--{physical_address} = '00-0C-29-5A-62-45'
|12426|1:15|image| |--%{vmnet1}
|12426|1:15|image| |--{autoconfiguration_enabled} = 'Yes'
|12426|1:15|image| |--{default_gateway} = '192.168.0.1'
|12426|1:15|image| |--{description} = 'VMware Accelerated AMD PCNet Adapter'
|12426|1:15|image| |--{dhcp_enabled} = 'Yes'
|12426|1:15|image| |--{dhcp_server} = '192.168.0.1'
|12426|1:15|image| |--%{vmnet1}{ip_address}
|12426|1:15|image| |--{192.168.1.1} = '255.255.0.0'
|12426|1:15|image| |--{lease_expires} = 'Thursday, February 04, 2010 11:03:36 
PM'
|12426|1:15|image| |--{lease_obtained} = 'Thursday, February 04, 2010 11:03:36 
AM'
|12426|1:15|image| |--{physical_address} = '00-0C-29-5A-62-4F'
|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, get_network_configuration (line: 4817)
|12426|1:15|image| (-2) Windows.pm, get_public_interface_name (line: 4871)
|12426|1:15|image| (-3) Windows.pm, disable_dynamic_dns (line: 3660)
|12426|1:15|image| (-4) Windows.pm, pre_capture (line: 211)
|12426|1:15|image| (-5) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-6) vmware.pm, capture (line: 1202)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:56:52|12426|1:15|image|Windows.pm:get_public_interface_name(4873)|unable to retrieve network configuration

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, get_public_interface_name (line: 4873)
|12426|1:15|image| (-2) Windows.pm, disable_dynamic_dns (line: 3660)
|12426|1:15|image| (-3) Windows.pm, pre_capture (line: 211)
|12426|1:15|image| (-4) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-5) vmware.pm, capture (line: 1202)
|12426|1:15|image| (-6) image.pm, process (line: 162)

2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4710)|network 
configuration has already been retrieved
2010-02-04 
13:56:52|12426|1:15|image|DataStructure.pm:get_computer_private_ip_address(1418)|returning
 private IP address previously retrieved from /etc/hosts: 192.168.1.1
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4760)|interface 
ignored because of name: vmnet0, description: VMware Accelerated AMD PCNet 
Adapter #2, address(es): 129.128.9.108
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4747)|private 
interface found: vmnet1, description: VMware Accelerated AMD PCNet Adapter, 
address(es): 192.168.1.1
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_network_configuration(4750)|returning 
data for private interface: vmnet1 (192.168.1.1)
2010-02-04 
13:56:52|12426|1:15|image|Windows.pm:get_private_interface_name(4848)|returning 
private interface name: vmnet1
Use of uninitialized value in concatenation (.) or string at
        /usr/local/vcl/bin/../lib/VCL/Module/OS/Windows.pm line 3666 (#1)
    (W uninitialized) An undefined value was used as if it were already
    defined.  It was interpreted as a "" or a 0, but maybe it was a mistake.
    To suppress this warning assign a defined value to your variables.

    
    To help you figure out what was undefined, perl tells you what operation

    you used the undefined value in.  Note, however, that perl optimizes your
    program and the operation displayed in the warning may not necessarily
    appear literally in your program.  For example, "that $foo" is
    usually optimized into "that " . $foo, and the warning will refer to
    the concatenation (.) operator, even though there is no . in your
    program.

    

|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:56:52|12426|1:15|image|vcld:warning_handler(636)|Use of uninitialized value in concatenation (.) or string at /usr/local/vcl/bin/../lib/VCL/Module/OS/Windows.pm line 3666.

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) vcld, warning_handler (line: 636)
|12426|1:15|image| (-2) Windows.pm, disable_dynamic_dns (line: 3666)
|12426|1:15|image| (-3) Windows.pm, pre_capture (line: 211)
|12426|1:15|image| (-4) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-5) vmware.pm, capture (line: 1202)
|12426|1:15|image| (-6) image.pm, process (line: 162)
___________________________




I'm not actually sure how bad those warning are.  The process keeps going for a 
while, with various additional warnings, some duplicating the above, some 
different.  Maybe it's worth posting the warnings that come towards the end:



___________________________
2010-02-04 13:58:30|12426|1:15|image|Windows.pm:reboot(2687)|rebooting 
vmguest-1 and waiting for ssh to become active
2010-02-04 13:58:30|12426|1:15|image|Windows.pm:reboot(2696)|reboot will be 
attempted on vmguest-1
2010-02-04 13:58:30|12426|1:15|image|Windows.pm:wait_for_ssh(3047)|waiting for 
vmguest-1 to respond to ssh, maximum of 0 minutes


|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|vcld:warning_handler(636)|Can't exec "/usr/bin/nmap": No such file or directory at /usr/local/vcl/bin/../lib/VCL/utils.pm line 3308.

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) vcld, warning_handler (line: 636)
|12426|1:15|image| (-2) utils.pm, nmap_port (line: 3308)
|12426|1:15|image| (-3) Windows.pm, wait_for_ssh (line: 3058)
|12426|1:15|image| (-4) Windows.pm, reboot (line: 2699)
|12426|1:15|image| (-5) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-6) Windows.pm, pre_capture (line: 318)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|utils.pm:nmap_port(3327)|problems executing /usr/bin/nmap vmguest-1 -P0 -p 22 Inappropriate ioctl for device

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) utils.pm, nmap_port (line: 3327)
|12426|1:15|image| (-2) Windows.pm, wait_for_ssh (line: 3058)
|12426|1:15|image| (-3) Windows.pm, reboot (line: 2699)
|12426|1:15|image| (-4) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-5) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-6) Version_5.pm, pre_capture (line: 85)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|vcld:warning_handler(636)|Can't exec "/usr/bin/nmap": No such file or directory at /usr/local/vcl/bin/../lib/VCL/utils.pm line 3308.

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) vcld, warning_handler (line: 636)
|12426|1:15|image| (-2) utils.pm, nmap_port (line: 3308)
|12426|1:15|image| (-3) Windows.pm, wait_for_ssh (line: 3058)
|12426|1:15|image| (-4) Windows.pm, reboot (line: 2699)
|12426|1:15|image| (-5) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-6) Windows.pm, pre_capture (line: 318)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|utils.pm:nmap_port(3327)|problems executing /usr/bin/nmap vmguest-1 -P0 -p 24 Inappropriate ioctl for device

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) utils.pm, nmap_port (line: 3327)
|12426|1:15|image| (-2) Windows.pm, wait_for_ssh (line: 3058)
|12426|1:15|image| (-3) Windows.pm, reboot (line: 2699)
|12426|1:15|image| (-4) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-5) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-6) Version_5.pm, pre_capture (line: 85)

2010-02-04 13:58:30|12426|1:15|image|Windows.pm:wait_for_ssh(3059)|ports 22 and 
24 are closed on vmguest-1 according to nmap


|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|Windows.pm:wait_for_ssh(3078)|vmguest-1 is not available via ssh

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, wait_for_ssh (line: 3078)
|12426|1:15|image| (-2) Windows.pm, reboot (line: 2699)
|12426|1:15|image| (-3) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-4) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-5) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-6) vmware.pm, capture (line: 1202)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|Windows.pm:reboot(2749)|vmguest-1 did not respond to ssh, graceful reboot cannot be performed, attempting hard reset

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, reboot (line: 2749)
|12426|1:15|image| (-2) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-3) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-4) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-5) vmware.pm, capture (line: 1202)
|12426|1:15|image| (-6) image.pm, process (line: 162)

2010-02-04 13:58:30|12426|1:15|image|vmware.pm:get_vm_paths(2655)|vm locations:
|12426|1:15|image| vmdk name: vmwarewinxp-base7-v0
|12426|1:15|image| vmx path: /var/lib/vmware/Virtual\ 
Machines/15vmguest-1/15vmguest-1.vmx
|12426|1:15|image| vm directory: 15vmguest-1
|12426|1:15|image| base vm directory: 15vmguest-1
2010-02-04 13:58:30|12426|1:15|image|vmware.pm:power_reset(2522)|attempting to 
reset vm using trysoft mode: 15vmguest-1
2010-02-04 13:58:30|12426|1:15|image|utils.pm:run_ssh_command(6180)|executing 
SSH command on localvmhost:
|12426|1:15|image| /usr/bin/ssh -i /etc/vcl/vcl.key  -l root -p 22 -x localvmhost 
'vmware-cmd /var/lib/vmware/Virtual\ Machines/15vmguest-1/15vmguest-1.vmx reset 
trysoft' 2>&1


|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 13:58:30|12426|1:15|image|vmware.pm:power_reset(2524)|failed to run vmware-cmd reset on 15vmguest-1, exit status: 11, output:

|12426|1:15|image| Use of uninitialized value in string eq at 
/usr/lib64/perl5/site_perl/5.8.8/x86_64-linux-thread-multi/VMware/VmPerl.pm line 114. 
/usr/bin/vmware-cmd: Could not connect to VM /var/lib/vmware/Virtual 
Machines/15vmguest-1/15vmguest-1.vmx   (VMControl error -11: No such virtual machine: The 
config file /var/lib/vmware/Virtual Machines/15vmguest-1/15vmguest-1.vmx is not 
registered. Please register the config file on the server.  For example: vmware-cmd -s 
register "/var/lib/vmware/Virtual Machines/15vmguest-1/15vmguest-1.vmx")
|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) vmware.pm, power_reset (line: 2524)
|12426|1:15|image| (-2) Windows.pm, reboot (line: 2752)
|12426|1:15|image| (-3) Windows.pm, disable_pagefile (line: 1799)
|12426|1:15|image| (-4) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-5) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-6) vmware.pm, capture (line: 1202)
_________________________




It then goes on to attempt (but fail) to restart the VM, and then try (but 
fail) to ping the VM.  Of the various warnings at the very end, the most 
interesting might be:





_________________________

|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 14:08:45|12426|1:15|image|Windows.pm:disable_pagefile(1803)|failed to reboot computer after disabling pagefile

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, disable_pagefile (line: 1803)
|12426|1:15|image| (-2) Windows.pm, pre_capture (line: 318)
|12426|1:15|image| (-3) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-4) vmware.pm, capture (line: 1202)
|12426|1:15|image| (-5) image.pm, process (line: 162)
|12426|1:15|image| (-6) vcld, make_new_child (line: 594)



|12426|1:15|image| ---- WARNING ---- 
|12426|1:15|image| 2010-02-04 14:08:45|12426|1:15|image|Windows.pm:pre_capture(319)|unable to disable pagefile

|12426|1:15|image| ( 0) utils.pm, notify (line: 737)
|12426|1:15|image| (-1) Windows.pm, pre_capture (line: 319)
|12426|1:15|image| (-2) Version_5.pm, pre_capture (line: 85)
|12426|1:15|image| (-3) vmware.pm, capture (line: 1202)
|12426|1:15|image| (-4) image.pm, process (line: 162)
|12426|1:15|image| (-5) vcld, make_new_child (line: 594)
|12426|1:15|image| (-6) vcld, main (line: 341)
________________________



I set the two network connections up on the vm as per the documentation here:

http://cwiki.apache.org/VCL/configuring-a-standalone-vmware-vcl-environment.html

I'm not sure how important it was to create both interfaces, but hopefully I 
did it right.

So, I'm pretty sure the end of the tunnel is getting closer, but I'm still not 
sure how far I have to go.  Any ideas about my current mess?  (And, thanks once 
again for all the help you're giving me.)

Terry


























					Reply via email to