All, I have been looking at Norvins log and I'm not sure I completely understand the process that occurs when reading in the vmx during an image capture.
|30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_vmx_info(4405)|attempting to retrieve info from vmx file: /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx |30380|24:24|image| ---- WARNING ---- |30380|24:24|image| 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:_get_datastore_name(5848)|unable to determine datastore name from path: '/tmp/6VUppBGwp2/vcl485.vmx', path does not begin with any of the datastore paths: >From these lines I see that it found the VM's vmx and wrote an edited copy of >the vmx to /tmp on the mgmt node, however I am unsure why it is trying to >determine the datastore name for that path since that is not a path located on >the share that is mounted to esx. Can someone please chime in and shed some light? - Gerhard -----Original Message----- From: Basilio, Norvin [mailto:nbasi...@odu.edu] Sent: Wednesday, May 30, 2012 11:54 AM To: vcl-user@incubator.apache.org Subject: Image Creation Help Needed I'm trying to capture a windows 7 image. I think I have overlooked something but am not able to find it. I'm running VCL 2.2.1 with ESXi 4.1 and I have installed the VSphere SDK for perl. Below is the log from the failed job. vmwarewin7-Windows764bitBase28-v0 image creation failed ------------------------------------------------------------------------ time: 2012-05-30 09:18:46 caller: image.pm:reservation_failed(385) ( 0) image.pm, reservation_failed (line: 385) (-1) image.pm, process (line: 167) (-2) vcld, make_new_child (line: 568) (-3) vcld, main (line: 346) ------------------------------------------------------------------------ management node: portman.vcls.priv.odu.edu reservation PID: 30380 parent vcld PID: 2738 request ID: 24 reservation ID: 24 request state/laststate: image/image request start time: 2012-05-30 09:17:54 request end time: 2012-05-30 10:17:54 for imaging: no log ID: none computer: vcl485 computer id: 5 computer type: virtualmachine computer eth0 MAC address: 00:50:56:00:03:c8 computer eth1 MAC address: 00:50:56:00:03:c9 computer private IP address: 192.168.131.245 computer public IP address: 128.82.131.245 computer in block allocation: no provisioning module: VCL::Module::Provisioning::VMware::VMware vm host: hepburn.vcls.priv.odu.edu vm host ID: 1 vm host computer ID: 1 vm profile: Default NFS Storage vm profile VM path: [VCL-VMPATH] vm profile repository path: [VCL-REPOSITORY] vm profile datastore path: [VCL-DATASTORE] vm profile disk type: networkdisk image: vmwarewin7-Windows764bitBase28-v0 image display name: Windows 7 64bit Base image ID: 28 image revision ID: 28 image size: 1450 MB use Sysprep: yes root access: yes image owner ID: 1 image owner affiliation: Local image revision date created: 2012-05-30 09:18:24 image revision production: yes OS module: VCL::Module::OS::Windows::Version_6::7 user: admin user name: vcl admin user ID: 1 user affiliation: Local ------------------------------------------------------------------------ RECENT LOG ENTRIES FOR THIS PROCESS: 2012-05-30 09:18:45|30380|24:24|image|vSphere_SDK.pm:_get_file_info(2002)|searching for matching file paths: base directory path: '[VCL-VMPATH]', search pattern: '*.vmx' 2012-05-30 09:18:45|30380|24:24|image|vSphere_SDK.pm:find_files(1750)|matching file count: 0 2012-05-30 09:18:45|30380|24:24|image|VMware.pm:get_vmx_file_paths(4337)|found 0 vmx files under /vmfs/volumes/VCL-VMPATH 2012-05-30 09:18:46|30380|24:24|image|vSphere_SDK.pm:get_registered_vms(99)|found 1 registered VMs: |30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_vmx_file_paths(4341)|found 1 registered vmx files |30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_vmx_file_paths(4346)|found 1 vmx files on VM host |30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_active_vmx_file_path(775)|retrieved vmx file paths currently residing on the VM host: |30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_active_vmx_file_path(782)|sorted vmx file paths so that directories containing vcl485 are checked first: |30380|24:24|image| /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:get_vmx_info(4405)|attempting to retrieve info from vmx file: /vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx |30380|24:24|image| ---- WARNING ---- |30380|24:24|image| 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:_get_datastore_name(5848)|unable to determine datastore name from path: '/tmp/6VUppBGwp2/vcl485.vmx', path does not begin with any of the datastore paths: |30380|24:24|image| '[VCL-VMPATH]' |30380|24:24|image| '/vmfs/volumes/VCL-VMPATH' |30380|24:24|image| '/vmfs/volumes/7f97aebd-35542d2b' |30380|24:24|image| '[VCL-DATASTORE]' |30380|24:24|image| '/vmfs/volumes/VCL-DATASTORE' |30380|24:24|image| '/vmfs/volumes/97ce3204-9ab49719' |30380|24:24|image| '[VCL-REPOSITORY]' |30380|24:24|image| '/vmfs/volumes/VCL-REPOSITORY' |30380|24:24|image| '/vmfs/volumes/782da00a-e2e7b496' |30380|24:24|image| '[datastore1]' |30380|24:24|image| '/vmfs/volumes/datastore1' |30380|24:24|image| '/vmfs/volumes/4f17e862-32251b14-0c6a-00215af214fa' |30380|24:24|image| ( 0) VMware.pm, _get_datastore_name (line: 5848) |30380|24:24|image| (-1) VMware.pm, _get_datastore_path (line: 5638) |30380|24:24|image| (-2) VMware.pm, _get_parent_directory_datastore_path |30380|(line: 5919) 24:24|image| (-3) VMware.pm, |30380|_get_parent_directory_normal_path (line: 5878) 24:24|image| (-4) |30380|vSphere_SDK.pm, copy_file_from (line: 1402) 24:24|image| (-5) |30380|vSphere_SDK.pm, get_file_contents (line: 1489) 24:24|image| ---- |30380|WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|VMware.pm:_get_datastore_path(5640)|un |30380|able to determine datastore path, failed to determine datastore |30380|name: /tmp/6VUppBGwp2/vcl485.vmx 24:24|image| ( 0) VMware.pm, |30380|_get_datastore_path (line: 5640) 24:24|image| (-1) VMware.pm, |30380|_get_parent_directory_datastore_path (line: 5919) 24:24|image| |30380|(-2) VMware.pm, _get_parent_directory_normal_path (line: 5878) |30380|24:24|image| (-3) vSphere_SDK.pm, copy_file_from (line: 1402) |30380|24:24|image| (-4) vSphere_SDK.pm, get_file_contents (line: 1489) 24:24|image| (-5) VMware.pm, get_vmx_info (line: 4409) 24:24|image| ---- WARNING ---- 24:24|image| 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:_get_parent_directory_datastore_path(5921)|unable to determine parent directory datastore path, path argument could not be converted to a datastore path: '/tmp/6VUppBGwp2/vcl485.vmx' |30380|24:24|image| ( 0) VMware.pm, _get_parent_directory_datastore_path |30380|(line: 5921) 24:24|image| (-1) VMware.pm, |30380|_get_parent_directory_normal_path (line: 5878) 24:24|image| (-2) |30380|vSphere_SDK.pm, copy_file_from (line: 1402) 24:24|image| (-3) |30380|vSphere_SDK.pm, get_file_contents (line: 1489) 24:24|image| (-4) |30380|VMware.pm, get_vmx_info (line: 4409) 24:24|image| (-5) VMware.pm, |30380|get_active_vmx_file_path (line: 792) 24:24|image| ---- WARNING |30380|---- 24:24|image| 2012-05-30 09:18:46|30380|24:24|image|VMware.pm:_get_parent_directory_normal_path(5880)|unable to determine parent directory normal path, parent directory datastore path could not be determined on which the normal path is based: '/tmp/6VUppBGwp2/vcl485.vmx' |30380|24:24|image| ( 0) VMware.pm, _get_parent_directory_normal_path |30380|(line: 5880) 24:24|image| (-1) vSphere_SDK.pm, copy_file_from |30380|(line: 1402) 24:24|image| (-2) vSphere_SDK.pm, get_file_contents |30380|(line: 1489) 24:24|image| (-3) VMware.pm, get_vmx_info (line: |30380|4409) 24:24|image| (-4) VMware.pm, get_active_vmx_file_path |30380|(line: 792) 24:24|image| (-5) VMware.pm, capture (line: 467) |30380|24:24|image| ---- WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|VMware.pm:get_vmx_info(4411)|unable to |30380|retrieve the contents of vmx file: |30380|/vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx |30380|24:24|image| ( 0) VMware.pm, get_vmx_info (line: 4411) |30380|24:24|image| (-1) VMware.pm, get_active_vmx_file_path (line: 792) |30380|24:24|image| (-2) VMware.pm, capture (line: 467) 24:24|image| |30380|(-3) image.pm, process (line: 162) 24:24|image| (-4) vcld, |30380|make_new_child (line: 568) 24:24|image| (-5) vcld, main (line: |30380|346) 24:24|image| ---- WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|VMware.pm:get_active_vmx_file_path(794 |30380|)|unable to retrieve the info from existing vmx file on VM host: |30380|/vmfs/volumes/VCL-REPOSITORY/vcl485/vcl485.vmx |30380|24:24|image| ( 0) VMware.pm, get_active_vmx_file_path (line: 794) |30380|24:24|image| (-1) VMware.pm, capture (line: 467) 24:24|image| |30380|(-2) image.pm, process (line: 162) 24:24|image| (-3) vcld, |30380|make_new_child (line: 568) 24:24|image| (-4) vcld, main (line: |30380|346) 24:24|image| ---- WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|VMware.pm:get_active_vmx_file_path(840 |30380|)|did not find any vmx files on the VM host containing a MAC |30380|address matching vcl485 24:24|image| ( 0) VMware.pm, |30380|get_active_vmx_file_path (line: 840) 24:24|image| (-1) VMware.pm, |30380|capture (line: 467) 24:24|image| (-2) image.pm, process (line: |30380|162) 24:24|image| (-3) vcld, make_new_child (line: 568) |30380|24:24|image| (-4) vcld, main (line: 346) 24:24|image| ---- |30380|WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|VMware.pm:capture(469)|failed to |30380|determine the vmx file path actively being used by VM vcl485 |30380|24:24|image| ( 0) VMware.pm, capture (line: 469) 24:24|image| |30380|(-1) image.pm, process (line: 162) 24:24|image| (-2) vcld, |30380|make_new_child (line: 568) 24:24|image| (-3) vcld, main (line: |30380|346) 24:24|image| ---- WARNING ---- 24:24|image| 2012-05-30 |30380|09:18:46|30380|24:24|image|image.pm:process(166)|vmwarewin7-Windo |30380|ws764bitBase28-v0 image failed to be captured by provisioning |30380|module 24:24|image| ( 0) image.pm, process (line: 166) |30380|24:24|image| (-1) vcld, make_new_child (line: 568) 24:24|image| |30380|(-2) vcld, main (line: 346) 2012-05-30 09:18:46|30380|24:24|image|DataStructure.pm:get_computer_private_ip_address(1557)|returning private IP address previously retrieved: 192.168.131.245 2012-05-30 09:18:46|30380|24:24|image|utils.pm:is_inblockrequest(6163)|zero rows were returned from database select 2012-05-30 09:18:46|30380|24:24|image|DataStructure.pm:get_image_affiliation_name(2035)|image owner id: 1 2012-05-30 09:18:46|30380|24:24|image|utils.pm:getnewdbh(2709)|database requested (information_schema) does not match handle stored in $ENV{dbh} (vcl:192.168.129.85) 2012-05-30 09:18:46|30380|24:24|image|utils.pm:getnewdbh(2760)|database handle stored in $ENV{dbh} 2012-05-30 09:18:46|30380|24:24|image|DataStructure.pm:retrieve_user_data(1352)|attempting to retrieve and store data for user: user.id = '1' 2012-05-30 09:18:46|30380|24:24|image|utils.pm:getnewdbh(2709)|database requested (vcl) does not match handle stored in $ENV{dbh} (information_schema:192.168.129.85) 2012-05-30 09:18:46|30380|24:24|image|utils.pm:getnewdbh(2760)|database handle stored in $ENV{dbh} 2012-05-30 09:18:46|30380|24:24|image|DataStructure.pm:retrieve_user_data(1415)|data has been retrieved for user: admin (id: 1) -- BEGIN-ANTISPAM-VOTING-LINKS ------------------------------------------------------ Teach CanIt if this mail (ID 671021547) is spam: Spam: https://www.spamtrap.odu.edu/b.php?i=671021547&m=4450c190e0d6&t=20120530&c=s Not spam: https://www.spamtrap.odu.edu/b.php?i=671021547&m=4450c190e0d6&t=20120530&c=n Forget vote: https://www.spamtrap.odu.edu/b.php?i=671021547&m=4450c190e0d6&t=20120530&c=f ------------------------------------------------------ END-ANTISPAM-VOTING-LINKS