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)

Reply via email to