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)
