You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@vcl.apache.org by "Hartl, Gerhard L." <GH...@odu.edu> on 2012/07/03 18:04:43 UTC
Error Capturing Base Image VCL 2.3
All,
While testing out (playing with) the 2.3 RC3 code we ran into an issue capturing a base image. The clones are successful to both the datastore and the repository containers however it seems to be failing while setting the image repository permissions.
Any thoughts?
VCL 2.3 RC3 (rhel 5.8 x64)
vSphere 4.1 SDK
vSphere 4.1 Standard
- Gerhard Hartl
Old Dominion University
SOAP Fault:
-----------
Fault string: entity
Fault detail: InvalidRequestFault
------------------------------------------------------------------------
time: 2012-07-02 17:20:28
caller: vcld:die_handler(639)
( 0) vcld, die_handler (line: 639)
(-1) Carp.pm, confess (line: 270)
(-2) VICommon.pm, check_fault (line: 28)
(-3) (eval 1686), RetrieveEntityPermissions (line: 34)
(-4) vSphere_SDK.pm, set_file_permissions (line: 1073)
(-5) VMware.pm, set_image_repository_permissions (line: 7655)
(-6) VMware.pm, capture (line: 884)
(-7) image.pm, process (line: 162)
(-8) vcld, make_new_child (line: 571)
(-9) vcld, main (line: 350)
------------------------------------------------------------------------
management node: MGMTNODE.vcls.priv.odu.edu reservation PID: 23141 parent vcld PID: 3164
request ID: 16
reservation ID: 16
request state/laststate: image/image
request start time: 2012-07-02 16:56:00
request end time: 2012-07-02 17:56:00
for imaging: no
log ID: none
computer: vcl485
computer id: 2
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.135 computer in block allocation: no provisioning module: VCL::Module::Provisioning::VMware::VMware
vm host: hepburn.vcls.priv.odu.edu
vm host ID: 2
vm host computer ID: 3
vm profile: VMware ESXi - General Use
vm profile VM path: VMPATH
vm profile repository path: REPOSITORY
vm profile datastore path: DATASTORE
vm profile disk type: shared
image: vmwarewin7-Windows764bitBase14-v0 image display name: Windows 7 64bit Base image ID: 14 image revision ID: 14 image size: 1450 MB use Sysprep: no root access: yes image owner ID: 1 image owner affiliation: Local image revision date created: 2012-07-02 16:56:06 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:
|23141|16:16|image| : {}
2012-07-02 17:13:23|23141|16:16|image|vSphere_SDK.pm:find_files(2149)|matching file count: 0
2012-07-02 17:13:23|23141|16:16|image|vSphere_SDK.pm:file_exists(2021)|file does not exist: [REPOSITORY] vmwarewin7-Windows764bitBase14-v0
2012-07-02 17:13:23|23141|16:16|image|vSphere_SDK.pm:create_directory(1575)|created directory: '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0'
2012-07-02 17:13:23|23141|16:16|image|vSphere_SDK.pm:copy_virtual_disk(684)|attempting to copy virtual disk on VM host hepburn.vcls.priv.odu.edu: '[DATASTORE] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk' --> '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk'
|23141|16:16|image| source adapter type: VirtualLsiLogicController
|23141|16:16|image| destination adapter type: ide 16:16|image| disk
|23141|type: VirtualDiskFlatVer2BackingInfo 16:16|image| source
|23141|capacity: 21,474,836,480 bytes - 20,971,520.0 KB - 20,480.0 MB -
|23141|20.00 GB 16:16|image| source space used: 13,119,586,304 bytes -
|23141|12,812,096.0 KB - 12,511.8 MB - 12.22 GB
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:copy_virtual_disk(736)|copied vmdk on VM host hepburn.vcls.priv.odu.edu using CopyVirtualDisk function:
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk"
2012-07-02 17:20:24|23141|16:16|image|VMware.pm:copy_vmdk(5814)|copied vmdk using API's copy_virtual_disk subroutine
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2549)|searching for matching file paths: base directory path: '[DATASTORE] vmwarewin7-Windows764bitBase14-v0', search pattern: 'vmwarewin7-Windows764bitBase14-v0.vmx.reference'
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2604)|retrieved info for 1 matching files:
|23141|16:16|image| : {
|23141|16:16|image| : "[DATASTORE] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference" => bless( {
|23141|16:16|image| : "fileSize" => 1974,
|23141|16:16|image| : "modification" => "2012-07-02T21:13:18Z",
|23141|16:16|image| : "owner" => "root",
|23141|16:16|image| : "path" => "vmwarewin7-Windows764bitBase14-v0.vmx.reference",
|23141|16:16|image| : "type" => "FileInfo"
|23141|16:16|image| : }, 'FileInfo' )
|23141|16:16|image| : }
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:find_files(2149)|matching file count: 1
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:file_exists(2017)|file exists: [DATASTORE] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference
2012-07-02 17:20:24|23141|16:16|image|VMware.pm:copy_vmdk(6071)|copying reference vmx file to vmdk directory: '/vmfs/volumes/DATASTORE/vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference' --> '/vmfs/volumes/REPOSITORY/vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference'
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2549)|searching for matching file paths: base directory path: '[REPOSITORY]', search pattern: 'vmwarewin7-Windows764bitBase14-v0'
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2604)|retrieved info for 0 matching files:
|23141|16:16|image| : {}
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:find_files(2149)|matching file count: 0
2012-07-02 17:20:24|23141|16:16|image|vSphere_SDK.pm:file_exists(2021)|file does not exist: [REPOSITORY] vmwarewin7-Windows764bitBase14-v0
2012-07-02 17:20:25|23141|16:16|image|vSphere_SDK.pm:create_directory(1566)|directory already exists: '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0'
2012-07-02 17:20:25|23141|16:16|image|vSphere_SDK.pm:copy_file(1681)|attempting to copy file on VM host hepburn.vcls.priv.odu.edu: '[DATASTORE] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference' --> '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference'
2012-07-02 17:20:25|23141|16:16|image|vSphere_SDK.pm:copy_file(1705)|copied file on VM host hepburn.vcls.priv.odu.edu: '[DATASTORE] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference' --> '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmx.reference'
2012-07-02 17:20:25|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2549)|searching for matching file paths: base directory path: '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0', search pattern: 'vmwarewin7-Windows764bitBase14-v0*.vmdk'
2012-07-02 17:20:27|23141|16:16|image|vSphere_SDK.pm:_get_file_info(2604)|retrieved info for 1 matching files:
|23141|16:16|image| : {
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk" => bless( {
|23141|16:16|image| : "capacityKb" => 20971520,
|23141|16:16|image| : "controllerType" => "VirtualLsiLogicController",
|23141|16:16|image| : "diskExtents" => [
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s001.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s002.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s003.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s004.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s005.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s006.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s007.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s008.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s009.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s010.vmdk",
|23141|16:16|image| : "[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0-s011.vmdk"
|23141|16:16|image| : ],
|23141|16:16|image| : "diskType" => "VirtualDiskSparseVer2BackingInfo",
|23141|16:16|image| : "fileSize" => "13122347008",
|23141|16:16|image| : "hardwareVersion" => 4,
|23141|16:16|image| : "modification" => "2012-07-02T21:20:19Z",
|23141|16:16|image| : "owner" => "root",
|23141|16:16|image| : "path" => "vmwarewin7-Windows764bitBase14-v0.vmdk",
|23141|16:16|image| : "thin" => 1,
|23141|16:16|image| : "type" => "VmDiskFileInfo"
|23141|16:16|image| : }, 'VmDiskFileInfo' )
|23141|16:16|image| : }
2012-07-02 17:20:27|23141|16:16|image|vSphere_SDK.pm:get_file_size(2081)|size of '[REPOSITORY] vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk': 13,122,347,008 bytes
2012-07-02 17:20:27|23141|16:16|image|vSphere_SDK.pm:get_file_size(2089)|total file size of '/vmfs/volumes/REPOSITORY/vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0*.vmdk': 13,122,347,008 bytes (12,514.45 MB, 12.22 GB)
2012-07-02 17:20:27|23141|16:16|image|VMware.pm:copy_vmdk(6115)|copied vmdk on VM host hepburn: '/vmfs/volumes/DATASTORE/vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk' --> '/vmfs/volumes/REPOSITORY/vmwarewin7-Windows764bitBase14-v0/vmwarewin7-Windows764bitBase14-v0.vmdk'
|23141|16:16|image| time to copy: 7:01 (421 seconds) 16:16|image| ---
|23141|16:16|image| bits copied: 104,978,776,064 (104978776064)
|23141|16:16|image| bytes copied: 13,122,347,008 (13122347008)
|23141|16:16|image| MB copied: 12,514.4
|23141|16:16|image| GB copied: 12.22
|23141|16:16|image| ---
|23141|16:16|image| B/m: 1,870,168,220
|23141|16:16|image| MB/m: 1,783.5
|23141|16:16|image| GB/m: 1.74
|23141|16:16|image| ---
|23141|16:16|image| B/s: 31,169,470
|23141|16:16|image| MB/s: 29.7
|23141|16:16|image| GB/s: 0.03
|23141|16:16|image| ---
|23141|16:16|image| Mbit/s: 237.8
|23141|16:16|image| Gbit/s: 0.23
2012-07-02 17:20:27|23141|16:16|image|VMware.pm:_get_datastore_names(6779)|datastore names:
|23141|16:16|image| DATASTORE
|23141|16:16|image| REPOSITORY
|23141|16:16|image| VMPATH
|23141|16:16|image| VNX
|23141|16:16|image| datastore1
2012-07-02 17:20:27|23141|16:16|image|vSphere_SDK.pm:file_exists(2002)|file (datastore root) exists: [REPOSITORY]
2012-07-02 17:20:27|23141|16:16|image|VMware.pm:is_repository_mounted_on_vmhost(6701)|image repository is mounted on VM host hepburn: /vmfs/volumes/REPOSITORY
2012-07-02 17:20:28|23141|16:16|image|vSphere_SDK.pm:set_file_permissions(1071)|created vSphere SDK authorization manager object
2012-07-02 17:20:28|23141|16:16|image|utils.pm:get_management_node_info(5482)|retrieving current management node info for 'MGMTNODE.vcls.priv.odu.edu' from database, cached data is stale: 909 seconds old
2012-07-02 17:20:28|23141|16:16|image|utils.pm:get_management_node_info(5629)|retrieved management node info: 'MGMTNODE.vcls.priv.odu.edu' (MGMTNODE)
2012-07-02 17:20:28|23141|16:16|image|DataStructure.pm:get_computer_private_ip_address(1606)|returning private IP address previously retrieved: 192.168.131.245
2012-07-02 17:20:28|23141|16:16|image|utils.pm:is_inblockrequest(5786)|zero rows were returned from database select
2012-07-02 17:20:28|23141|16:16|image|DataStructure.pm:get_image_affiliation_name(2118)|image owner id: 1
2012-07-02 17:20:28|23141|16:16|image|DataStructure.pm:retrieve_user_data(1401)|attempting to retrieve and store data for user: user.id = '1'
2012-07-02 17:20:28|23141|16:16|image|DataStructure.pm:retrieve_user_data(1464)|data has been retrieved for user: admin (id: 1)