You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by Zapata Vivas Darío <da...@anahuac.mx> on 2012/07/02 17:40:10 UTC
Problem to create Base Image - VCL 2.2
Dmitri
Thanks, I corrected the problem with language. Now I have the next error logs:
2012-07-02 09:32:13|8053|44:44|image|Windows.pm:get_driver_inf_paths(8726)|attempting to locate driver .inf paths matching any class
2012-07-02 09:32:13|8053|44:44|image|utils.pm:run_ssh_command(5442)|executing SSH command on lab-template1:
|8053|44:44|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x lab-template1 '/usr/bin/grep.exe -Eirl --include="*.[iI][nN][fF]" ".*" C:/Cygwin/home/root/VCL/Drivers' 2>&1
2012-07-02 09:32:16|8053|44:44|image|Windows.pm:get_driver_inf_paths(8750)|found 0 driver .inf paths, grep output:
|8053|44:44|image| ---- WARNING ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:set_device_path_key(8794)|failed to locate driver .inf paths
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) Windows.pm, set_device_path_key (line: 8794)
|8053|44:44|image| (-2) Windows.pm, prepare_post_load (line: 3490)
|8053|44:44|image| (-3) 7.pm, pre_capture (line: 177)
|8053|44:44|image| (-4) VMware.pm, capture (line: 485)
|8053|44:44|image| (-5) image.pm, process (line: 165)
|8053|44:44|image| (-6) vcld, make_new_child (line: 595)
|8053|44:44|image| ---- WARNING ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:prepare_post_load(3491)|failed to set the DevicePath registry key
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) Windows.pm, prepare_post_load (line: 3491)
|8053|44:44|image| (-2) 7.pm, pre_capture (line: 177)
|8053|44:44|image| (-3) VMware.pm, capture (line: 485)
|8053|44:44|image| (-4) image.pm, process (line: 165)
|8053|44:44|image| (-5) vcld, make_new_child (line: 595)
|8053|44:44|image| (-6) vcld, main (line: 342)
|8053|44:44|image| ---- WARNING ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|7.pm:pre_capture(178)|capture preparation failed, failed to run prepare post_load
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) 7.pm, pre_capture (line: 178)
|8053|44:44|image| (-2) VMware.pm, capture (line: 485)
|8053|44:44|image| (-3) image.pm, process (line: 165)
|8053|44:44|image| (-4) vcld, make_new_child (line: 595)
|8053|44:44|image| (-5) vcld, main (line: 342)
|8053|44:44|image| ---- WARNING ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|VMware.pm:capture(486)|failed to complete OS module's pre_capture tasks
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) VMware.pm, capture (line: 486)
|8053|44:44|image| (-2) image.pm, process (line: 165)
|8053|44:44|image| (-3) vcld, make_new_child (line: 595)
|8053|44:44|image| (-4) vcld, main (line: 342)
|8053|44:44|image| ---- WARNING ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:process(169)|vmwarewin7-lab0545-v0 image failed to be captured by provisioning module
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) image.pm, process (line: 169)
|8053|44:44|image| (-2) vcld, make_new_child (line: 595)
|8053|44:44|image| (-3) vcld, main (line: 342)
2012-07-02 09:32:16|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, PROBLEM -- image.pm
|8053|44:44|image| ---- CRITICAL ----
|8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:reservation_failed(393)|vmwarewin7-lab0545-v0 image creation failed
|8053|44:44|image| ( 0) utils.pm, notify (line: 630)
|8053|44:44|image| (-1) image.pm, reservation_failed (line: 393)
|8053|44:44|image| (-2) image.pm, process (line: 170)
|8053|44:44|image| (-3) vcld, make_new_child (line: 595)
|8053|44:44|image| (-4) vcld, main (line: 342)
2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation lab05
2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL -- NOTICE FAILED Image Creation lab05
2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_request_state(2049)|request 44 state updated to: maintenance, laststate to: image
2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_computer_state(2091)|computer 9 state updated to: maintenance
2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(453)|lab-template1 state set to maintenance
2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(460)|exiting
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=44
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(921)|number of database handles state process created: 1
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(946)|VCL::image process 8053 exiting
2012-07-02 09:32:17|19357|vcld:REAPER(745)|VCL process exited for reservation 44, PID: 8053, signal: CHLD
Any idea?
Saludos cordiales
Darío Zapata Vivas
Administrador de Servidores
UNIVERSIDAD ANÁHUAC - MÉXICO NORTE
DIRECCIÓN DE SERVICIOS TECNOLÓGICOS
De: dchebota@gmu.edu [mailto:dchebota@gmu.edu]
Enviado el: viernes, 29 de junio de 2012 10:15 a.m.
Para: vcl-user@incubator.apache.org
Asunto: Re: Problem to create Base Image - VCL 2.2
Zapata
The problem may be related to a different language.
get_network_configuration is looking for line with word 'adapter' to determine interface name (i.e. "Ethernet adapter Local Area Connection"), which is not present in your case.
Windows.pm: get_network_configuration
# Find beginning of interface section
if ($line =~ /\A[^\s].*adapter (.*):\s*\Z/i) {
# Get the interface name
$interface_name = $1;
...
# Skip line if interface hasn't been found yet
next if !$interface_name;
Thanks.
On Jun 29, 2012, at 10:57 , Zapata Vivas Darío wrote:
Hi
I have installed VCL2.2 (-web code and data base in a public server & management node in a private server) but I have a problem to create the base image.
When I execute the /usr/local/vcl/bin/vcld -setup command the vcld log shows that the connection to the VM Host is ok in both vSphere_SDK and SSH but some errors to create the base image are generated, this the output of the log:
2012-06-29 08:22:49|14875|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=31
2012-06-29 08:22:49|14875|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=31
2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9010)|computerloadlog 'begin' entry does NOT exist for reservation 31
2012-06-29 08:22:51|19357|31:31|image|utils.pm:run_command(9082)|executed command: pgrep -fl 'vcld [0-9]+:31 ', pid: 14895, exit status: 1, output:
2012-06-29 08:22:51|19357|31:31|image|utils.pm:is_management_node_process_running(9259)|process is NOT running, identifier: 'vcld [0-9]+:31 '
2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9031)|reservation is NOT currently being processed
2012-06-29 08:22:51|19357|31:31|image|vcld:main(277)|reservation 31 is NOT already being processed
2012-06-29 08:22:51|19357|31:31|image|utils.pm:get_request_info(4589)|standalone affiliation found: Local
2012-06-29 08:22:51|19357|31:31|image|vcld:main(282)|retrieved request information from database
2012-06-29 08:22:51|19357|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
2012-06-29 08:22:51|19357|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: pending, laststate to: image
2012-06-29 08:22:51|19357|31:31|image|utils.pm:insertloadlog(3933)|inserted computer=5, begin, beginning to process, state is image
2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(510)|loaded VCL::image module
2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(567)|current number of forked kids: 1
2012-06-29 08:22:51|14897|31:31|image|vcld:make_new_child(583)|vcld environment variable set to 0 for this process
2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::image
2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::image object created
2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh}
2012-06-29 08:22:51|14897|31:31|image|State.pm:check_image_os(836)|no corrections need to be made to image OS: vmwarewin7
2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7183)|reservation count: 1
2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7202)|PARENTIMAGE: 1
2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7203)|SUBIMAGE: 0
2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7221)|renamed process to 'vcld 31:31 image plantilla1 vmwarewin7-testt0335-v0 admin'
2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(118)|attempting to load provisioning module: VCL::Module::Provisioning::VMware::VMware
2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(124)|VCL::Module::Provisioning::VMware::VMware module loaded
2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::VMware
2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VMware object created
2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
2012-06-29 08:22:52|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1027)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1033)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::vSphere_SDK
2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::vSphere_SDK object created
2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1819)|attempting to connect to VM host: https://esx04/sdk <https://esx04/sdk>
2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1829)|connected to VM host: https://esx04/sdk <https://esx04/sdk>
2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1046)|created API object: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-06-29 08:22:53|14897|31:31|image|vSphere_SDK.pm:is_restricted(1082)|access to the VM host via the vSphere SDK is NOT restricted due to the license
2012-06-29 08:22:53|14897|31:31|image|VMware.pm:initialize(229)|vSphere SDK object will be used to control the VM: plantilla1, and to control the OS of the VM host: esx04
2012-06-29 08:22:58|14897|31:31|image|vSphere_SDK.pm:get_vmware_product_name(958)|VMware product being used on VM host esx04: 'VMware ESXi 5.0.0 build-469512'
2012-06-29 08:23:05|14897|31:31|image|vSphere_SDK.pm:file_exists(1561)|file (datastore root) exists: [LUN vnx 04]
2012-06-29 08:23:05|14897|31:31|image|VMware.pm:initialize(294)|VMware provisioning object initialized:
|14897|31:31|image| VM host OS object type: VCL::Module::Provisioning::VMware::vSphere_SDK
|14897|31:31|image| API object type: VCL::Module::Provisioning::VMware::vSphere_SDK
2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware provisioner object created
2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(142)|attempting to load OS module: VCL::Module::OS::Windows::Version_6::7
2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(148)|VCL::Module::OS::Windows::Version_6::7 module loaded
2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::OS::Windows::Version_6::7
2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(156)|VCL::Module::OS::Windows::Version_6::7 object created
2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_6::7 OS object created
2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(166)|returning 1
2012-06-29 08:23:05|14897|31:31|image|vcld:make_new_child(592)|VCL::image object created and initialized
2012-06-29 08:23:05|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx <ma...@anahuac.mx> , VCL IMAGE Creation Started: vmwarewin7-testt0335-v0
2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1940)|searching for matching file paths: base directory path: '[LUN vnx 04] vmwarewin7-testt0335-v0', search pattern: 'vmwarewin7-testt0335-v0.vmdk'
2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1951)|base directory does not exist: '[LUN vnx 04] vmwarewin7-testt0335-v0'
2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:find_files(1708)|matching file count: 0
2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:file_exists(1580)|file does not exist: [LUN vnx 04] vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2940)|image does not exist in the non-persistent directory on the VM host, checking the image repository
2012-06-29 08:23:12|14897|31:31|image|VMware.pm:get_repository_vmdk_base_directory_path(2654)|repository path is not set for the VM profile, using management node install path: /vmfs/volumes/vmware_images
2012-06-29 08:23:12|14897|31:31|image|utils.pm:run_command(9082)|executed command: find "/vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0" -iname "vmwarewin7-testt0335-v0.vmdk", pid: 14925, exit status: 1, output:
|14897|31:31|image| find: /vmfs/volumes/vmware_images: No such file or directory
2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2967)|image does not exist in image repository: /vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
2012-06-29 08:23:12|14897|31:31|image|image.pm:process(148)|image vmwarewin7-testt0335-v0 does not exist in the repository
2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{image}{lastupdate}
|14897|31:31|image| image_lastupdate = 2012-06-29 08:23:12
2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{imagerevision}{datecreated}
|14897|31:31|image| imagerevision_date_created = 2012-06-29 08:23:12
2012-06-29 08:23:12|14897|31:31|image|image.pm:process(164)|calling provisioning module's capture() subroutine
2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
2012-06-29 08:23:15|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
2012-06-29 08:23:15|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
2012-06-29 08:23:16|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
2012-06-29 08:23:16|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
2012-06-29 08:23:17|14897|31:31|image|Windows.pm:is_64_bit(7704)|64-bit Windows OS detected, PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 44 Stepping 2, GenuineIntel
2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_system32_path(7749)|64-bit Windows OS installed on plantilla1, using C:/Windows/Sysnative
2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_network_configuration(4714)|attempting to retrieve network configuration information from plantilla1
2012-06-29 08:23:17|14897|31:31|image|utils.pm:run_ssh_command(5442)|executing SSH command on plantilla1:
|14897|31:31|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' 2>&1
2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4805)|saving network configuration in $self->{network_configuration}
2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1533)|attempting to retrieve private IP address for computer: plantilla1
2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1537)|retrieved contents of /etc/hosts on this management node, contains 7 lines
2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1590)|returning IP address from /etc/hosts file: 172.19.18.15
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4907)|did not find an interface using the private IP address for the reservation: 172.19.18.15
|14897|31:31|image| : {}
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4907)
|14897|31:31|image| (-2) Windows.pm, get_private_mac_address (line: 4999)
|14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
|14897|31:31|image| (-4) VMware.pm, capture (line: 408)
|14897|31:31|image| (-5) image.pm, process (line: 165)
|14897|31:31|image| (-6) vcld, make_new_child (line: 595)
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_private_mac_address(5001)|unable to retrieve network configuration
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) Windows.pm, get_private_mac_address (line: 5001)
|14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
|14897|31:31|image| (-3) VMware.pm, capture (line: 408)
|14897|31:31|image| (-4) image.pm, process (line: 165)
|14897|31:31|image| (-5) vcld, make_new_child (line: 595)
|14897|31:31|image| (-6) vcld, main (line: 342)
2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4809)|network configuration has already been retrieved
2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1509)|returning private IP address previously retrieved from /etc/hosts: 172.19.18.15
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4915)|unable to determine the public interface:
|14897|31:31|image| : {}
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4915)
|14897|31:31|image| (-2) Windows.pm, get_public_mac_address (line: 5029)
|14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
|14897|31:31|image| (-4) VMware.pm, capture (line: 408)
|14897|31:31|image| (-5) image.pm, process (line: 165)
|14897|31:31|image| (-6) vcld, make_new_child (line: 595)
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_public_mac_address(5031)|unable to retrieve network configuration
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) Windows.pm, get_public_mac_address (line: 5031)
|14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
|14897|31:31|image| (-3) VMware.pm, capture (line: 408)
|14897|31:31|image| (-4) image.pm, process (line: 165)
|14897|31:31|image| (-5) vcld, make_new_child (line: 595)
|14897|31:31|image| (-6) vcld, main (line: 342)
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:get_active_vmx_file_path(620)|unable to retrieve the private and public MAC address being used by VM plantilla1
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) VMware.pm, get_active_vmx_file_path (line: 620)
|14897|31:31|image| (-2) VMware.pm, capture (line: 408)
|14897|31:31|image| (-3) image.pm, process (line: 165)
|14897|31:31|image| (-4) vcld, make_new_child (line: 595)
|14897|31:31|image| (-5) vcld, main (line: 342)
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:capture(410)|failed to determine the vmx file path actively being used by plantilla1
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) VMware.pm, capture (line: 410)
|14897|31:31|image| (-2) image.pm, process (line: 165)
|14897|31:31|image| (-3) vcld, make_new_child (line: 595)
|14897|31:31|image| (-4) vcld, main (line: 342)
|14897|31:31|image| ---- WARNING ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:process(169)|vmwarewin7-testt0335-v0 image failed to be captured by provisioning module
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) image.pm, process (line: 169)
|14897|31:31|image| (-2) vcld, make_new_child (line: 595)
|14897|31:31|image| (-3) vcld, main (line: 342)
2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx <ma...@anahuac.mx> , PROBLEM -- image.pm
|14897|31:31|image| ---- CRITICAL ----
|14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:reservation_failed(393)|vmwarewin7-testt0335-v0 image creation failed
|14897|31:31|image| ( 0) utils.pm, notify (line: 630)
|14897|31:31|image| (-1) image.pm, reservation_failed (line: 393)
|14897|31:31|image| (-2) image.pm, process (line: 170)
|14897|31:31|image| (-3) vcld, make_new_child (line: 595)
|14897|31:31|image| (-4) vcld, main (line: 342)
2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation testt03
2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx <ma...@anahuac.mx> , VCL -- NOTICE FAILED Image Creation testt03
2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: maintenance, laststate to: image
2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_computer_state(2091)|computer 5 state updated to: maintenance
2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(453)|plantilla1 state set to maintenance
2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(460)|exiting
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=31
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(921)|number of database handles state process created: 1
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(946)|VCL::image process 14897 exiting
2012-06-29 08:23:19|19357|vcld:REAPER(745)|VCL process exited for reservation 31, PID: 14897, signal: CHLD
* vSphere _SDK
In our esx server do not exist the vmware_images directory and the structure of the name for the .vmdk files is different (Virtual9910-flat.vmdk) to the structure showed in the log (vmwarewin7-testt0335-v0.vmdk)
* SSH
The log shows that the system is unable to retrieve the public and private network configuration but de command/usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' shows this:
Configuraci¢n IP de Windows
Nombre de host. . . . . . . . . : plantilla
Sufijo DNS principal . . . . . : ua.mydomain.org
Tipo de nodo. . . . . . . . . . : h¡brido
Enrutamiento IP habilitado. . . : no
Proxy WINS habilitado . . . . . : no
Lista de b£squeda de sufijos DNS: ua.mydomain.org
mydomain.org
Adaptador de Ethernet Conexi¢n de rea local 2:
Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT #2
Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-6E-8B
DHCP habilitado . . . . . . . . . . . . . : s¡
Configuraci¢n autom tica habilitada . . . : s¡
Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.15(Preferido)
M scara de subred . . . . . . . . . . . . : 255.255.255.0
Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 04:49:10 a.m.
La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 04:49:10 a.m.
Puerta de enlace predeterminada . . . . . : 172.19.18.1
Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
172.19.14.42
NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
Adaptador de Ethernet Conexi¢n de rea local:
Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT
Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-7C-34
DHCP habilitado . . . . . . . . . . . . . : s¡
Configuraci¢n autom tica habilitada . . . : s¡
Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.133(Preferido)
M scara de subred . . . . . . . . . . . . : 255.255.255.0
Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 03:26:59 a.m.
La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 03:27:00 a.m.
Puerta de enlace predeterminada . . . . . : 172.19.18.1
Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
172.19.14.42
NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
Adaptador de t£nel isatap.ua.reduniv.org:
Estado de los medios. . . . . . . . . . . : medios desconectados
Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
Descripci¢n . . . . . . . . . . . . . . . : Adaptador ISATAP de Microsoft
Direcci¢n f¡sica. . . . . . . . . . . . . : 00-00-00-00-00-00-00-E0
DHCP habilitado . . . . . . . . . . . . . : no
Configuraci¢n autom tica habilitada . . . : s¡
Both IP addresses and MAC Addresses for the public and private networks was configured in Managed Computers.
Any idea to solve this problem?
Kind regards
Darío Zapata
Información Confidencial.
La información transmitida es para el uso exclusivo de la persona o
entidad a quien va dirigida, y puede contener información de carácter
confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
distinta al destinatario, cualquier revisión, retransmisión, distribución u
otro uso de la información. Si recibió este mensaje por equivocación,
atentamente le solicitamos eliminar la información de cualquier equipo
de cómputo y hacerlo del conocimiento del remitente.
--
Thank you,
Dmitri Chebotarov
Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
223 Aquia Building, Ffx, MSN: 1B5
Phone: (703) 993-6175
Fax: (703) 993-3404
Información Confidencial.
La información transmitida es para el uso exclusivo de la persona o
entidad a quien va dirigida, y puede contener información de carácter
confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
distinta al destinatario, cualquier revisión, retransmisión, distribución u
otro uso de la información. Si recibió este mensaje por equivocación,
atentamente le solicitamos eliminar la información de cualquier equipo
de cómputo y hacerlo del conocimiento del remitente.
Re: Problem to create Base Image - VCL 2.2
Posted by Dmitri Chebotarov <dc...@gmu.edu>.
Zapata
Are you using VCL 2.2 or VCL 2.2.1?
Thanks.
On Jul 2, 2012, at 12:02 , Dmitri Chebotarov wrote:
>
> Hmm... I'm not sure why it fails when cannot locate any drivers (.inf files).
> I checked mine Windows VMs and they don't have anything under c:/cygwin/home/root/VCL/Drivers folder and yet I was able to capture the image.
>
> May be someone else has an idea.
>
> Thanks.
>
> On Jul 2, 2012, at 11:40 , Zapata Vivas Darío wrote:
>
>> Dmitri
>>
>> Thanks, I corrected the problem with language. Now I have the next error logs:
>>
>> 2012-07-02 09:32:13|8053|44:44|image|Windows.pm:get_driver_inf_paths(8726)|attempting to locate driver .inf paths matching any class
>> 2012-07-02 09:32:13|8053|44:44|image|utils.pm:run_ssh_command(5442)|executing SSH command on lab-template1:
>> |8053|44:44|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x lab-template1 '/usr/bin/grep.exe -Eirl --include="*.[iI][nN][fF]" ".*" C:/Cygwin/home/root/VCL/Drivers' 2>&1
>> 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:get_driver_inf_paths(8750)|found 0 driver .inf paths, grep output:
>> |8053|44:44|image| ---- WARNING ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:set_device_path_key(8794)|failed to locate driver .inf paths
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) Windows.pm, set_device_path_key (line: 8794)
>> |8053|44:44|image| (-2) Windows.pm, prepare_post_load (line: 3490)
>> |8053|44:44|image| (-3) 7.pm, pre_capture (line: 177)
>> |8053|44:44|image| (-4) VMware.pm, capture (line: 485)
>> |8053|44:44|image| (-5) image.pm, process (line: 165)
>> |8053|44:44|image| (-6) vcld, make_new_child (line: 595)
>> |8053|44:44|image| ---- WARNING ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:prepare_post_load(3491)|failed to set the DevicePath registry key
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) Windows.pm, prepare_post_load (line: 3491)
>> |8053|44:44|image| (-2) 7.pm, pre_capture (line: 177)
>> |8053|44:44|image| (-3) VMware.pm, capture (line: 485)
>> |8053|44:44|image| (-4) image.pm, process (line: 165)
>> |8053|44:44|image| (-5) vcld, make_new_child (line: 595)
>> |8053|44:44|image| (-6) vcld, main (line: 342)
>> |8053|44:44|image| ---- WARNING ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|7.pm:pre_capture(178)|capture preparation failed, failed to run prepare post_load
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) 7.pm, pre_capture (line: 178)
>> |8053|44:44|image| (-2) VMware.pm, capture (line: 485)
>> |8053|44:44|image| (-3) image.pm, process (line: 165)
>> |8053|44:44|image| (-4) vcld, make_new_child (line: 595)
>> |8053|44:44|image| (-5) vcld, main (line: 342)
>> |8053|44:44|image| ---- WARNING ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|VMware.pm:capture(486)|failed to complete OS module's pre_capture tasks
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) VMware.pm, capture (line: 486)
>> |8053|44:44|image| (-2) image.pm, process (line: 165)
>> |8053|44:44|image| (-3) vcld, make_new_child (line: 595)
>> |8053|44:44|image| (-4) vcld, main (line: 342)
>> |8053|44:44|image| ---- WARNING ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:process(169)|vmwarewin7-lab0545-v0 image failed to be captured by provisioning module
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) image.pm, process (line: 169)
>> |8053|44:44|image| (-2) vcld, make_new_child (line: 595)
>> |8053|44:44|image| (-3) vcld, main (line: 342)
>> 2012-07-02 09:32:16|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, PROBLEM -- image.pm
>> |8053|44:44|image| ---- CRITICAL ----
>> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:reservation_failed(393)|vmwarewin7-lab0545-v0 image creation failed
>> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
>> |8053|44:44|image| (-1) image.pm, reservation_failed (line: 393)
>> |8053|44:44|image| (-2) image.pm, process (line: 170)
>> |8053|44:44|image| (-3) vcld, make_new_child (line: 595)
>> |8053|44:44|image| (-4) vcld, main (line: 342)
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation lab05
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL -- NOTICE FAILED Image Creation lab05
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_request_state(2049)|request 44 state updated to: maintenance, laststate to: image
>> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_computer_state(2091)|computer 9 state updated to: maintenance
>> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(453)|lab-template1 state set to maintenance
>> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(460)|exiting
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
>> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=44
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(921)|number of database handles state process created: 1
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
>> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(946)|VCL::image process 8053 exiting
>> 2012-07-02 09:32:17|19357|vcld:REAPER(745)|VCL process exited for reservation 44, PID: 8053, signal: CHLD
>>
>> Any idea?
>>
>>
>> Saludos cordiales
>>
>> Darío Zapata Vivas
>> Administrador de Servidores
>> UNIVERSIDAD ANÁHUAC - MÉXICO NORTE
>> DIRECCIÓN DE SERVICIOS TECNOLÓGICOS
>>
>> De: dchebota@gmu.edu [mailto:dchebota@gmu.edu]
>> Enviado el: viernes, 29 de junio de 2012 10:15 a.m.
>> Para: vcl-user@incubator.apache.org
>> Asunto: Re: Problem to create Base Image - VCL 2.2
>>
>> Zapata
>>
>> The problem may be related to a different language.
>> get_network_configuration is looking for line with word 'adapter' to determine interface name (i.e. "Ethernet adapter Local Area Connection"), which is not present in your case.
>>
>> Windows.pm: get_network_configuration
>> # Find beginning of interface section
>> if ($line =~ /\A[^\s].*adapter (.*):\s*\Z/i) {
>> # Get the interface name
>> $interface_name = $1;
>>
>> ...
>>
>> # Skip line if interface hasn't been found yet
>> next if !$interface_name;
>>
>>
>>
>> Thanks.
>>
>> On Jun 29, 2012, at 10:57 , Zapata Vivas Darío wrote:
>>
>>
>> Hi
>>
>> I have installed VCL2.2 (-web code and data base in a public server & management node in a private server) but I have a problem to create the base image.
>>
>> When I execute the /usr/local/vcl/bin/vcld –setup command the vcld log shows that the connection to the VM Host is ok in both vSphere_SDK and SSH but some errors to create the base image are generated, this the output of the log:
>>
>>
>> 2012-06-29 08:22:49|14875|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=31
>> 2012-06-29 08:22:49|14875|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=31
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9010)|computerloadlog 'begin' entry does NOT exist for reservation 31
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:run_command(9082)|executed command: pgrep -fl 'vcld [0-9]+:31 ', pid: 14895, exit status: 1, output:
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:is_management_node_process_running(9259)|process is NOT running, identifier: 'vcld [0-9]+:31 '
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9031)|reservation is NOT currently being processed
>> 2012-06-29 08:22:51|19357|31:31|image|vcld:main(277)|reservation 31 is NOT already being processed
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:get_request_info(4589)|standalone affiliation found: Local
>> 2012-06-29 08:22:51|19357|31:31|image|vcld:main(282)|retrieved request information from database
>> 2012-06-29 08:22:51|19357|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: pending, laststate to: image
>> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:insertloadlog(3933)|inserted computer=5, begin, beginning to process, state is image
>> 2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(510)|loaded VCL::image module
>> 2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(567)|current number of forked kids: 1
>> 2012-06-29 08:22:51|14897|31:31|image|vcld:make_new_child(583)|vcld environment variable set to 0 for this process
>> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::image
>> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::image object created
>> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh}
>> 2012-06-29 08:22:51|14897|31:31|image|State.pm:check_image_os(836)|no corrections need to be made to image OS: vmwarewin7
>> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
>> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7183)|reservation count: 1
>> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7202)|PARENTIMAGE: 1
>> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7203)|SUBIMAGE: 0
>> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7221)|renamed process to 'vcld 31:31 image plantilla1 vmwarewin7-testt0335-v0 admin'
>> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
>> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
>> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(118)|attempting to load provisioning module: VCL::Module::Provisioning::VMware::VMware
>> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(124)|VCL::Module::Provisioning::VMware::VMware module loaded
>> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::VMware
>> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VMware object created
>> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
>> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
>> 2012-06-29 08:22:52|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
>> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
>> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1027)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
>> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1033)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
>> 2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::vSphere_SDK
>> 2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::vSphere_SDK object created
>> 2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1819)|attempting to connect to VM host: https://esx04/sdk
>> 2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1829)|connected to VM host: https://esx04/sdk
>> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1046)|created API object: VCL::Module::Provisioning::VMware::vSphere_SDK
>> 2012-06-29 08:22:53|14897|31:31|image|vSphere_SDK.pm:is_restricted(1082)|access to the VM host via the vSphere SDK is NOT restricted due to the license
>> 2012-06-29 08:22:53|14897|31:31|image|VMware.pm:initialize(229)|vSphere SDK object will be used to control the VM: plantilla1, and to control the OS of the VM host: esx04
>> 2012-06-29 08:22:58|14897|31:31|image|vSphere_SDK.pm:get_vmware_product_name(958)|VMware product being used on VM host esx04: 'VMware ESXi 5.0.0 build-469512'
>> 2012-06-29 08:23:05|14897|31:31|image|vSphere_SDK.pm:file_exists(1561)|file (datastore root) exists: [LUN vnx 04]
>> 2012-06-29 08:23:05|14897|31:31|image|VMware.pm:initialize(294)|VMware provisioning object initialized:
>> |14897|31:31|image| VM host OS object type: VCL::Module::Provisioning::VMware::vSphere_SDK
>> |14897|31:31|image| API object type: VCL::Module::Provisioning::VMware::vSphere_SDK
>> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware provisioner object created
>> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(142)|attempting to load OS module: VCL::Module::OS::Windows::Version_6::7
>> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(148)|VCL::Module::OS::Windows::Version_6::7 module loaded
>> 2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::OS::Windows::Version_6::7
>> 2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(156)|VCL::Module::OS::Windows::Version_6::7 object created
>> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_6::7 OS object created
>> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(166)|returning 1
>> 2012-06-29 08:23:05|14897|31:31|image|vcld:make_new_child(592)|VCL::image object created and initialized
>> 2012-06-29 08:23:05|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL IMAGE Creation Started: vmwarewin7-testt0335-v0
>> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1940)|searching for matching file paths: base directory path: '[LUN vnx 04] vmwarewin7-testt0335-v0', search pattern: 'vmwarewin7-testt0335-v0.vmdk'
>> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1951)|base directory does not exist: '[LUN vnx 04] vmwarewin7-testt0335-v0'
>> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:find_files(1708)|matching file count: 0
>> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:file_exists(1580)|file does not exist: [LUN vnx 04] vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
>> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2940)|image does not exist in the non-persistent directory on the VM host, checking the image repository
>> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:get_repository_vmdk_base_directory_path(2654)|repository path is not set for the VM profile, using management node install path: /vmfs/volumes/vmware_images
>> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:run_command(9082)|executed command: find "/vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0" -iname "vmwarewin7-testt0335-v0.vmdk", pid: 14925, exit status: 1, output:
>> |14897|31:31|image| find: /vmfs/volumes/vmware_images: No such file or directory
>> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2967)|image does not exist in image repository: /vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
>> 2012-06-29 08:23:12|14897|31:31|image|image.pm:process(148)|image vmwarewin7-testt0335-v0 does not exist in the repository
>> 2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{image}{lastupdate}
>> |14897|31:31|image| image_lastupdate = 2012-06-29 08:23:12
>> 2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{imagerevision}{datecreated}
>> |14897|31:31|image| imagerevision_date_created = 2012-06-29 08:23:12
>> 2012-06-29 08:23:12|14897|31:31|image|image.pm:process(164)|calling provisioning module's capture() subroutine
>> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
>> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
>> 2012-06-29 08:23:15|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
>> 2012-06-29 08:23:15|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
>> 2012-06-29 08:23:16|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
>> 2012-06-29 08:23:16|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
>> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:is_64_bit(7704)|64-bit Windows OS detected, PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 44 Stepping 2, GenuineIntel
>> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_system32_path(7749)|64-bit Windows OS installed on plantilla1, using C:/Windows/Sysnative
>> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_network_configuration(4714)|attempting to retrieve network configuration information from plantilla1
>> 2012-06-29 08:23:17|14897|31:31|image|utils.pm:run_ssh_command(5442)|executing SSH command on plantilla1:
>> |14897|31:31|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' 2>&1
>> 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4805)|saving network configuration in $self->{network_configuration}
>> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1533)|attempting to retrieve private IP address for computer: plantilla1
>> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1537)|retrieved contents of /etc/hosts on this management node, contains 7 lines
>> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1590)|returning IP address from /etc/hosts file: 172.19.18.15
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4907)|did not find an interface using the private IP address for the reservation: 172.19.18.15
>> |14897|31:31|image| : {}
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4907)
>> |14897|31:31|image| (-2) Windows.pm, get_private_mac_address (line: 4999)
>> |14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
>> |14897|31:31|image| (-4) VMware.pm, capture (line: 408)
>> |14897|31:31|image| (-5) image.pm, process (line: 165)
>> |14897|31:31|image| (-6) vcld, make_new_child (line: 595)
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_private_mac_address(5001)|unable to retrieve network configuration
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) Windows.pm, get_private_mac_address (line: 5001)
>> |14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
>> |14897|31:31|image| (-3) VMware.pm, capture (line: 408)
>> |14897|31:31|image| (-4) image.pm, process (line: 165)
>> |14897|31:31|image| (-5) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-6) vcld, main (line: 342)
>> 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4809)|network configuration has already been retrieved
>> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1509)|returning private IP address previously retrieved from /etc/hosts: 172.19.18.15
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4915)|unable to determine the public interface:
>> |14897|31:31|image| : {}
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4915)
>> |14897|31:31|image| (-2) Windows.pm, get_public_mac_address (line: 5029)
>> |14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
>> |14897|31:31|image| (-4) VMware.pm, capture (line: 408)
>> |14897|31:31|image| (-5) image.pm, process (line: 165)
>> |14897|31:31|image| (-6) vcld, make_new_child (line: 595)
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_public_mac_address(5031)|unable to retrieve network configuration
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) Windows.pm, get_public_mac_address (line: 5031)
>> |14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
>> |14897|31:31|image| (-3) VMware.pm, capture (line: 408)
>> |14897|31:31|image| (-4) image.pm, process (line: 165)
>> |14897|31:31|image| (-5) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-6) vcld, main (line: 342)
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:get_active_vmx_file_path(620)|unable to retrieve the private and public MAC address being used by VM plantilla1
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) VMware.pm, get_active_vmx_file_path (line: 620)
>> |14897|31:31|image| (-2) VMware.pm, capture (line: 408)
>> |14897|31:31|image| (-3) image.pm, process (line: 165)
>> |14897|31:31|image| (-4) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-5) vcld, main (line: 342)
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:capture(410)|failed to determine the vmx file path actively being used by plantilla1
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) VMware.pm, capture (line: 410)
>> |14897|31:31|image| (-2) image.pm, process (line: 165)
>> |14897|31:31|image| (-3) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-4) vcld, main (line: 342)
>> |14897|31:31|image| ---- WARNING ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:process(169)|vmwarewin7-testt0335-v0 image failed to be captured by provisioning module
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) image.pm, process (line: 169)
>> |14897|31:31|image| (-2) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-3) vcld, main (line: 342)
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, PROBLEM -- image.pm
>> |14897|31:31|image| ---- CRITICAL ----
>> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:reservation_failed(393)|vmwarewin7-testt0335-v0 image creation failed
>> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
>> |14897|31:31|image| (-1) image.pm, reservation_failed (line: 393)
>> |14897|31:31|image| (-2) image.pm, process (line: 170)
>> |14897|31:31|image| (-3) vcld, make_new_child (line: 595)
>> |14897|31:31|image| (-4) vcld, main (line: 342)
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation testt03
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL -- NOTICE FAILED Image Creation testt03
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: maintenance, laststate to: image
>> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_computer_state(2091)|computer 5 state updated to: maintenance
>> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(453)|plantilla1 state set to maintenance
>> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(460)|exiting
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
>> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=31
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(921)|number of database handles state process created: 1
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
>> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(946)|VCL::image process 14897 exiting
>> 2012-06-29 08:23:19|19357|vcld:REAPER(745)|VCL process exited for reservation 31, PID: 14897, signal: CHLD
>>
>>
>> * vSphere _SDK
>>
>> In our esx server do not exist the vmware_images directory and the structure of the name for the .vmdk files is different (Virtual9910-flat.vmdk) to the structure showed in the log (vmwarewin7-testt0335-v0.vmdk)
>>
>> * SSH
>>
>> The log shows that the system is unable to retrieve the public and private network configuration but de command/usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' shows this:
>>
>> Configuraci¢n IP de Windows
>>
>> Nombre de host. . . . . . . . . : plantilla
>> Sufijo DNS principal . . . . . : ua.mydomain.org
>> Tipo de nodo. . . . . . . . . . : h¡brido
>> Enrutamiento IP habilitado. . . : no
>> Proxy WINS habilitado . . . . . : no
>> Lista de b£squeda de sufijos DNS: ua.mydomain.org
>> mydomain.org
>>
>> Adaptador de Ethernet Conexi¢n de rea local 2:
>>
>> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
>> Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT #2
>> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-6E-8B
>> DHCP habilitado . . . . . . . . . . . . . : s¡
>> Configuraci¢n autom tica habilitada . . . : s¡
>> Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.15(Preferido)
>> M scara de subred . . . . . . . . . . . . : 255.255.255.0
>> Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 04:49:10 a.m.
>> La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 04:49:10 a.m.
>> Puerta de enlace predeterminada . . . . . : 172.19.18.1
>> Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
>> Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
>> 172.19.14.42
>> NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
>>
>> Adaptador de Ethernet Conexi¢n de rea local:
>>
>> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
>> Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT
>> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-7C-34
>> DHCP habilitado . . . . . . . . . . . . . : s¡
>> Configuraci¢n autom tica habilitada . . . : s¡
>> Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.133(Preferido)
>> M scara de subred . . . . . . . . . . . . : 255.255.255.0
>> Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 03:26:59 a.m.
>> La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 03:27:00 a.m.
>> Puerta de enlace predeterminada . . . . . : 172.19.18.1
>> Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
>> Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
>> 172.19.14.42
>> NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
>>
>> Adaptador de t£nel isatap.ua.reduniv.org:
>>
>> Estado de los medios. . . . . . . . . . . : medios desconectados
>> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
>> Descripci¢n . . . . . . . . . . . . . . . : Adaptador ISATAP de Microsoft
>> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-00-00-00-00-00-00-E0
>> DHCP habilitado . . . . . . . . . . . . . : no
>> Configuraci¢n autom tica habilitada . . . : s¡
>>
>> Both IP addresses and MAC Addresses for the public and private networks was configured in Managed Computers.
>>
>>
>> Any idea to solve this problem?
>>
>>
>> Kind regards
>>
>> Darío Zapata
>>
>> Información Confidencial.
>> La información transmitida es para el uso exclusivo de la persona o
>> entidad a quien va dirigida, y puede contener información de carácter
>> confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
>> distinta al destinatario, cualquier revisión, retransmisión, distribución u
>> otro uso de la información. Si recibió este mensaje por equivocación,
>> atentamente le solicitamos eliminar la información de cualquier equipo
>> de cómputo y hacerlo del conocimiento del remitente.
>>
>>
>>
>> --
>> Thank you,
>>
>> Dmitri Chebotarov
>> Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
>> 223 Aquia Building, Ffx, MSN: 1B5
>> Phone: (703) 993-6175
>> Fax: (703) 993-3404
>>
>> Información Confidencial.
>> La información transmitida es para el uso exclusivo de la persona o
>> entidad a quien va dirigida, y puede contener información de carácter
>> confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
>> distinta al destinatario, cualquier revisión, retransmisión, distribución u
>> otro uso de la información. Si recibió este mensaje por equivocación,
>> atentamente le solicitamos eliminar la información de cualquier equipo
>> de cómputo y hacerlo del conocimiento del remitente.
>
>
>
> --
> Thank you,
>
> Dmitri Chebotarov
> Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
> 223 Aquia Building, Ffx, MSN: 1B5
> Phone: (703) 993-6175
> Fax: (703) 993-3404
>
--
Thank you,
Dmitri Chebotarov
Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
223 Aquia Building, Ffx, MSN: 1B5
Phone: (703) 993-6175
Fax: (703) 993-3404
Re: Problem to create Base Image - VCL 2.2
Posted by Dmitri Chebotarov <dc...@gmu.edu>.
Hmm... I'm not sure why it fails when cannot locate any drivers (.inf files).
I checked mine Windows VMs and they don't have anything under c:/cygwin/home/root/VCL/Drivers folder and yet I was able to capture the image.
May be someone else has an idea.
Thanks.
On Jul 2, 2012, at 11:40 , Zapata Vivas Darío wrote:
> Dmitri
>
> Thanks, I corrected the problem with language. Now I have the next error logs:
>
> 2012-07-02 09:32:13|8053|44:44|image|Windows.pm:get_driver_inf_paths(8726)|attempting to locate driver .inf paths matching any class
> 2012-07-02 09:32:13|8053|44:44|image|utils.pm:run_ssh_command(5442)|executing SSH command on lab-template1:
> |8053|44:44|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x lab-template1 '/usr/bin/grep.exe -Eirl --include="*.[iI][nN][fF]" ".*" C:/Cygwin/home/root/VCL/Drivers' 2>&1
> 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:get_driver_inf_paths(8750)|found 0 driver .inf paths, grep output:
> |8053|44:44|image| ---- WARNING ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:set_device_path_key(8794)|failed to locate driver .inf paths
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) Windows.pm, set_device_path_key (line: 8794)
> |8053|44:44|image| (-2) Windows.pm, prepare_post_load (line: 3490)
> |8053|44:44|image| (-3) 7.pm, pre_capture (line: 177)
> |8053|44:44|image| (-4) VMware.pm, capture (line: 485)
> |8053|44:44|image| (-5) image.pm, process (line: 165)
> |8053|44:44|image| (-6) vcld, make_new_child (line: 595)
> |8053|44:44|image| ---- WARNING ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|Windows.pm:prepare_post_load(3491)|failed to set the DevicePath registry key
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) Windows.pm, prepare_post_load (line: 3491)
> |8053|44:44|image| (-2) 7.pm, pre_capture (line: 177)
> |8053|44:44|image| (-3) VMware.pm, capture (line: 485)
> |8053|44:44|image| (-4) image.pm, process (line: 165)
> |8053|44:44|image| (-5) vcld, make_new_child (line: 595)
> |8053|44:44|image| (-6) vcld, main (line: 342)
> |8053|44:44|image| ---- WARNING ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|7.pm:pre_capture(178)|capture preparation failed, failed to run prepare post_load
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) 7.pm, pre_capture (line: 178)
> |8053|44:44|image| (-2) VMware.pm, capture (line: 485)
> |8053|44:44|image| (-3) image.pm, process (line: 165)
> |8053|44:44|image| (-4) vcld, make_new_child (line: 595)
> |8053|44:44|image| (-5) vcld, main (line: 342)
> |8053|44:44|image| ---- WARNING ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|VMware.pm:capture(486)|failed to complete OS module's pre_capture tasks
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) VMware.pm, capture (line: 486)
> |8053|44:44|image| (-2) image.pm, process (line: 165)
> |8053|44:44|image| (-3) vcld, make_new_child (line: 595)
> |8053|44:44|image| (-4) vcld, main (line: 342)
> |8053|44:44|image| ---- WARNING ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:process(169)|vmwarewin7-lab0545-v0 image failed to be captured by provisioning module
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) image.pm, process (line: 169)
> |8053|44:44|image| (-2) vcld, make_new_child (line: 595)
> |8053|44:44|image| (-3) vcld, main (line: 342)
> 2012-07-02 09:32:16|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, PROBLEM -- image.pm
> |8053|44:44|image| ---- CRITICAL ----
> |8053|44:44|image| 2012-07-02 09:32:16|8053|44:44|image|image.pm:reservation_failed(393)|vmwarewin7-lab0545-v0 image creation failed
> |8053|44:44|image| ( 0) utils.pm, notify (line: 630)
> |8053|44:44|image| (-1) image.pm, reservation_failed (line: 393)
> |8053|44:44|image| (-2) image.pm, process (line: 170)
> |8053|44:44|image| (-3) vcld, make_new_child (line: 595)
> |8053|44:44|image| (-4) vcld, main (line: 342)
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation lab05
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL -- NOTICE FAILED Image Creation lab05
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_request_state(2049)|request 44 state updated to: maintenance, laststate to: image
> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:update_computer_state(2091)|computer 9 state updated to: maintenance
> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(453)|lab-template1 state set to maintenance
> 2012-07-02 09:32:17|8053|44:44|image|image.pm:reservation_failed(460)|exiting
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
> 2012-07-02 09:32:17|8053|44:44|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=44
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(921)|number of database handles state process created: 1
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
> 2012-07-02 09:32:17|8053|44:44|image|State.pm:DESTROY(946)|VCL::image process 8053 exiting
> 2012-07-02 09:32:17|19357|vcld:REAPER(745)|VCL process exited for reservation 44, PID: 8053, signal: CHLD
>
> Any idea?
>
>
> Saludos cordiales
>
> Darío Zapata Vivas
> Administrador de Servidores
> UNIVERSIDAD ANÁHUAC - MÉXICO NORTE
> DIRECCIÓN DE SERVICIOS TECNOLÓGICOS
>
> De: dchebota@gmu.edu [mailto:dchebota@gmu.edu]
> Enviado el: viernes, 29 de junio de 2012 10:15 a.m.
> Para: vcl-user@incubator.apache.org
> Asunto: Re: Problem to create Base Image - VCL 2.2
>
> Zapata
>
> The problem may be related to a different language.
> get_network_configuration is looking for line with word 'adapter' to determine interface name (i.e. "Ethernet adapter Local Area Connection"), which is not present in your case.
>
> Windows.pm: get_network_configuration
> # Find beginning of interface section
> if ($line =~ /\A[^\s].*adapter (.*):\s*\Z/i) {
> # Get the interface name
> $interface_name = $1;
>
> ...
>
> # Skip line if interface hasn't been found yet
> next if !$interface_name;
>
>
>
> Thanks.
>
> On Jun 29, 2012, at 10:57 , Zapata Vivas Darío wrote:
>
>
> Hi
>
> I have installed VCL2.2 (-web code and data base in a public server & management node in a private server) but I have a problem to create the base image.
>
> When I execute the /usr/local/vcl/bin/vcld –setup command the vcld log shows that the connection to the VM Host is ok in both vSphere_SDK and SSH but some errors to create the base image are generated, this the output of the log:
>
>
> 2012-06-29 08:22:49|14875|utils.pm:insert_request(8443)|inserted new reload request into request table, request id=31
> 2012-06-29 08:22:49|14875|utils.pm:insert_request(8474)|inserted new reload request into reservation table, reservation id=31
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9010)|computerloadlog 'begin' entry does NOT exist for reservation 31
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:run_command(9082)|executed command: pgrep -fl 'vcld [0-9]+:31 ', pid: 14895, exit status: 1, output:
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:is_management_node_process_running(9259)|process is NOT running, identifier: 'vcld [0-9]+:31 '
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:reservation_being_processed(9031)|reservation is NOT currently being processed
> 2012-06-29 08:22:51|19357|31:31|image|vcld:main(277)|reservation 31 is NOT already being processed
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:get_request_info(4589)|standalone affiliation found: Local
> 2012-06-29 08:22:51|19357|31:31|image|vcld:main(282)|retrieved request information from database
> 2012-06-29 08:22:51|19357|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: pending, laststate to: image
> 2012-06-29 08:22:51|19357|31:31|image|utils.pm:insertloadlog(3933)|inserted computer=5, begin, beginning to process, state is image
> 2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(510)|loaded VCL::image module
> 2012-06-29 08:22:51|19357|31:31|image|vcld:make_new_child(567)|current number of forked kids: 1
> 2012-06-29 08:22:51|14897|31:31|image|vcld:make_new_child(583)|vcld environment variable set to 0 for this process
> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::image
> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::image object created
> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(85)|obtained a database handle for this state process, stored as $ENV{dbh}
> 2012-06-29 08:22:51|14897|31:31|image|State.pm:check_image_os(836)|no corrections need to be made to image OS: vmwarewin7
> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7183)|reservation count: 1
> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7202)|PARENTIMAGE: 1
> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7203)|SUBIMAGE: 0
> 2012-06-29 08:22:51|14897|31:31|image|utils.pm:rename_vcld_process(7221)|renamed process to 'vcld 31:31 image plantilla1 vmwarewin7-testt0335-v0 admin'
> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:is_parent_reservation(943)|returning true: parent reservation ID for this request: 31
> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(118)|attempting to load provisioning module: VCL::Module::Provisioning::VMware::VMware
> 2012-06-29 08:22:51|14897|31:31|image|State.pm:initialize(124)|VCL::Module::Provisioning::VMware::VMware module loaded
> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::VMware
> 2012-06-29 08:22:51|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::VMware object created
> 2012-06-29 08:22:51|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
> 2012-06-29 08:22:52|14897|31:31|image|DataStructure.pm:_initialize(594)|computer ID argument was specified, retrieving data for computer ID: 1
> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_datastructure(923)|created DataStructure object for VM host: esx04
> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1027)|attempting to load VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1033)|loaded VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::Provisioning::VMware::vSphere_SDK
> 2012-06-29 08:22:52|14897|31:31|image|Module.pm:new(156)|VCL::Module::Provisioning::VMware::vSphere_SDK object created
> 2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1819)|attempting to connect to VM host: https://esx04/sdk
> 2012-06-29 08:22:52|14897|31:31|image|vSphere_SDK.pm:initialize(1829)|connected to VM host: https://esx04/sdk
> 2012-06-29 08:22:52|14897|31:31|image|VMware.pm:get_vmhost_api_object(1046)|created API object: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2012-06-29 08:22:53|14897|31:31|image|vSphere_SDK.pm:is_restricted(1082)|access to the VM host via the vSphere SDK is NOT restricted due to the license
> 2012-06-29 08:22:53|14897|31:31|image|VMware.pm:initialize(229)|vSphere SDK object will be used to control the VM: plantilla1, and to control the OS of the VM host: esx04
> 2012-06-29 08:22:58|14897|31:31|image|vSphere_SDK.pm:get_vmware_product_name(958)|VMware product being used on VM host esx04: 'VMware ESXi 5.0.0 build-469512'
> 2012-06-29 08:23:05|14897|31:31|image|vSphere_SDK.pm:file_exists(1561)|file (datastore root) exists: [LUN vnx 04]
> 2012-06-29 08:23:05|14897|31:31|image|VMware.pm:initialize(294)|VMware provisioning object initialized:
> |14897|31:31|image| VM host OS object type: VCL::Module::Provisioning::VMware::vSphere_SDK
> |14897|31:31|image| API object type: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(128)|VCL::Module::Provisioning::VMware::VMware provisioner object created
> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(142)|attempting to load OS module: VCL::Module::OS::Windows::Version_6::7
> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(148)|VCL::Module::OS::Windows::Version_6::7 module loaded
> 2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(134)|constructor called, class=VCL::Module::OS::Windows::Version_6::7
> 2012-06-29 08:23:05|14897|31:31|image|Module.pm:new(156)|VCL::Module::OS::Windows::Version_6::7 object created
> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(151)|VCL::Module::OS::Windows::Version_6::7 OS object created
> 2012-06-29 08:23:05|14897|31:31|image|State.pm:initialize(166)|returning 1
> 2012-06-29 08:23:05|14897|31:31|image|vcld:make_new_child(592)|VCL::image object created and initialized
> 2012-06-29 08:23:05|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL IMAGE Creation Started: vmwarewin7-testt0335-v0
> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1940)|searching for matching file paths: base directory path: '[LUN vnx 04] vmwarewin7-testt0335-v0', search pattern: 'vmwarewin7-testt0335-v0.vmdk'
> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:get_file_info(1951)|base directory does not exist: '[LUN vnx 04] vmwarewin7-testt0335-v0'
> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:find_files(1708)|matching file count: 0
> 2012-06-29 08:23:12|14897|31:31|image|vSphere_SDK.pm:file_exists(1580)|file does not exist: [LUN vnx 04] vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2940)|image does not exist in the non-persistent directory on the VM host, checking the image repository
> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:get_repository_vmdk_base_directory_path(2654)|repository path is not set for the VM profile, using management node install path: /vmfs/volumes/vmware_images
> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:run_command(9082)|executed command: find "/vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0" -iname "vmwarewin7-testt0335-v0.vmdk", pid: 14925, exit status: 1, output:
> |14897|31:31|image| find: /vmfs/volumes/vmware_images: No such file or directory
> 2012-06-29 08:23:12|14897|31:31|image|VMware.pm:does_image_exist(2967)|image does not exist in image repository: /vmfs/volumes/vmware_images/vmwarewin7-testt0335-v0/vmwarewin7-testt0335-v0.vmdk
> 2012-06-29 08:23:12|14897|31:31|image|image.pm:process(148)|image vmwarewin7-testt0335-v0 does not exist in the repository
> 2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{image}{lastupdate}
> |14897|31:31|image| image_lastupdate = 2012-06-29 08:23:12
> 2012-06-29 08:23:12|14897|31:31|image|DataStructure.pm:_automethod(786)|data structure updated: $self->request_data->{reservation}{31}{imagerevision}{datecreated}
> |14897|31:31|image| imagerevision_date_created = 2012-06-29 08:23:12
> 2012-06-29 08:23:12|14897|31:31|image|image.pm:process(164)|calling provisioning module's capture() subroutine
> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
> 2012-06-29 08:23:12|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
> 2012-06-29 08:23:15|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
> 2012-06-29 08:23:15|14897|31:31|image|utils.pm:nmap_port(2699)|port 22 is open on plantilla1
> 2012-06-29 08:23:16|14897|31:31|image|utils.pm:nmap_port(2707)|port 24 is closed on plantilla1
> 2012-06-29 08:23:16|14897|31:31|image|OS.pm:is_ssh_responding(430)|plantilla1 is responding to SSH, port 22: open, port 24: closed
> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:is_64_bit(7704)|64-bit Windows OS detected, PROCESSOR_IDENTIFIER: Intel64 Family 6 Model 44 Stepping 2, GenuineIntel
> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_system32_path(7749)|64-bit Windows OS installed on plantilla1, using C:/Windows/Sysnative
> 2012-06-29 08:23:17|14897|31:31|image|Windows.pm:get_network_configuration(4714)|attempting to retrieve network configuration information from plantilla1
> 2012-06-29 08:23:17|14897|31:31|image|utils.pm:run_ssh_command(5442)|executing SSH command on plantilla1:
> |14897|31:31|image| /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' 2>&1
> 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4805)|saving network configuration in $self->{network_configuration}
> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1533)|attempting to retrieve private IP address for computer: plantilla1
> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1537)|retrieved contents of /etc/hosts on this management node, contains 7 lines
> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1590)|returning IP address from /etc/hosts file: 172.19.18.15
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4907)|did not find an interface using the private IP address for the reservation: 172.19.18.15
> |14897|31:31|image| : {}
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4907)
> |14897|31:31|image| (-2) Windows.pm, get_private_mac_address (line: 4999)
> |14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
> |14897|31:31|image| (-4) VMware.pm, capture (line: 408)
> |14897|31:31|image| (-5) image.pm, process (line: 165)
> |14897|31:31|image| (-6) vcld, make_new_child (line: 595)
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_private_mac_address(5001)|unable to retrieve network configuration
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) Windows.pm, get_private_mac_address (line: 5001)
> |14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
> |14897|31:31|image| (-3) VMware.pm, capture (line: 408)
> |14897|31:31|image| (-4) image.pm, process (line: 165)
> |14897|31:31|image| (-5) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-6) vcld, main (line: 342)
> 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4809)|network configuration has already been retrieved
> 2012-06-29 08:23:18|14897|31:31|image|DataStructure.pm:get_computer_private_ip_address(1509)|returning private IP address previously retrieved from /etc/hosts: 172.19.18.15
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_network_configuration(4915)|unable to determine the public interface:
> |14897|31:31|image| : {}
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) Windows.pm, get_network_configuration (line: 4915)
> |14897|31:31|image| (-2) Windows.pm, get_public_mac_address (line: 5029)
> |14897|31:31|image| (-3) VMware.pm, get_active_vmx_file_path (line: 618)
> |14897|31:31|image| (-4) VMware.pm, capture (line: 408)
> |14897|31:31|image| (-5) image.pm, process (line: 165)
> |14897|31:31|image| (-6) vcld, make_new_child (line: 595)
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|Windows.pm:get_public_mac_address(5031)|unable to retrieve network configuration
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) Windows.pm, get_public_mac_address (line: 5031)
> |14897|31:31|image| (-2) VMware.pm, get_active_vmx_file_path (line: 618)
> |14897|31:31|image| (-3) VMware.pm, capture (line: 408)
> |14897|31:31|image| (-4) image.pm, process (line: 165)
> |14897|31:31|image| (-5) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-6) vcld, main (line: 342)
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:get_active_vmx_file_path(620)|unable to retrieve the private and public MAC address being used by VM plantilla1
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) VMware.pm, get_active_vmx_file_path (line: 620)
> |14897|31:31|image| (-2) VMware.pm, capture (line: 408)
> |14897|31:31|image| (-3) image.pm, process (line: 165)
> |14897|31:31|image| (-4) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-5) vcld, main (line: 342)
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|VMware.pm:capture(410)|failed to determine the vmx file path actively being used by plantilla1
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) VMware.pm, capture (line: 410)
> |14897|31:31|image| (-2) image.pm, process (line: 165)
> |14897|31:31|image| (-3) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-4) vcld, main (line: 342)
> |14897|31:31|image| ---- WARNING ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:process(169)|vmwarewin7-testt0335-v0 image failed to be captured by provisioning module
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) image.pm, process (line: 169)
> |14897|31:31|image| (-2) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-3) vcld, main (line: 342)
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, PROBLEM -- image.pm
> |14897|31:31|image| ---- CRITICAL ----
> |14897|31:31|image| 2012-06-29 08:23:18|14897|31:31|image|image.pm:reservation_failed(393)|vmwarewin7-testt0335-v0 image creation failed
> |14897|31:31|image| ( 0) utils.pm, notify (line: 630)
> |14897|31:31|image| (-1) image.pm, reservation_failed (line: 393)
> |14897|31:31|image| (-2) image.pm, process (line: 170)
> |14897|31:31|image| (-3) vcld, make_new_child (line: 595)
> |14897|31:31|image| (-4) vcld, main (line: 342)
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation testt03
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:mail(1247)|SUCCESS -- Sending mail To: dario.zapata@anahuac.mx, VCL -- NOTICE FAILED Image Creation testt03
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_request_state(2049)|request 31 state updated to: maintenance, laststate to: image
> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(445)|request state set to maintenance, laststate to image
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:update_computer_state(2091)|computer 5 state updated to: maintenance
> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(453)|plantilla1 state set to maintenance
> 2012-06-29 08:23:19|14897|31:31|image|image.pm:reservation_failed(460)|exiting
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(902)|destructor called, ref($self)=VCL::image
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6829)|removing computerloadlog entries matching loadstate = begin
> 2012-06-29 08:23:19|14897|31:31|image|utils.pm:delete_computerloadlog_reservation(6876)|deleted rows from computerloadlog for reservation id=31
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(909)|removed computerloadlog rows with loadstate=begin for reservation
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(921)|number of database handles state process created: 1
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(930)|process has a database handle stored in $ENV{dbh}, attempting disconnect
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(932)|$ENV{dbh}: database disconnect successful
> 2012-06-29 08:23:19|14897|31:31|image|State.pm:DESTROY(946)|VCL::image process 14897 exiting
> 2012-06-29 08:23:19|19357|vcld:REAPER(745)|VCL process exited for reservation 31, PID: 14897, signal: CHLD
>
>
> * vSphere _SDK
>
> In our esx server do not exist the vmware_images directory and the structure of the name for the .vmdk files is different (Virtual9910-flat.vmdk) to the structure showed in the log (vmwarewin7-testt0335-v0.vmdk)
>
> * SSH
>
> The log shows that the system is unable to retrieve the public and private network configuration but de command/usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x plantilla1 'C:/Windows/Sysnative/ipconfig.exe /all' shows this:
>
> Configuraci¢n IP de Windows
>
> Nombre de host. . . . . . . . . : plantilla
> Sufijo DNS principal . . . . . : ua.mydomain.org
> Tipo de nodo. . . . . . . . . . : h¡brido
> Enrutamiento IP habilitado. . . : no
> Proxy WINS habilitado . . . . . : no
> Lista de b£squeda de sufijos DNS: ua.mydomain.org
> mydomain.org
>
> Adaptador de Ethernet Conexi¢n de rea local 2:
>
> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
> Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT #2
> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-6E-8B
> DHCP habilitado . . . . . . . . . . . . . : s¡
> Configuraci¢n autom tica habilitada . . . : s¡
> Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.15(Preferido)
> M scara de subred . . . . . . . . . . . . : 255.255.255.0
> Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 04:49:10 a.m.
> La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 04:49:10 a.m.
> Puerta de enlace predeterminada . . . . . : 172.19.18.1
> Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
> Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
> 172.19.14.42
> NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
>
> Adaptador de Ethernet Conexi¢n de rea local:
>
> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
> Descripci¢n . . . . . . . . . . . . . . . : Conexi¢n de red Intel(R) PRO/1000 MT
> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-50-56-97-7C-34
> DHCP habilitado . . . . . . . . . . . . . : s¡
> Configuraci¢n autom tica habilitada . . . : s¡
> Direcci¢n IPv4. . . . . . . . . . . . . . : 172.19.18.133(Preferido)
> M scara de subred . . . . . . . . . . . . : 255.255.255.0
> Concesi¢n obtenida. . . . . . . . . . . . : jueves, 28 de junio de 2012 03:26:59 a.m.
> La concesi¢n expira . . . . . . . . . . . : mircoles, 26 de septiembre de 2012 03:27:00 a.m.
> Puerta de enlace predeterminada . . . . . : 172.19.18.1
> Servidor DHCP . . . . . . . . . . . . . . : 172.19.14.109
> Servidores DNS. . . . . . . . . . . . . . : 172.19.14.41
> 172.19.14.42
> NetBIOS sobre TCP/IP. . . . . . . . . . . : habilitado
>
> Adaptador de t£nel isatap.ua.reduniv.org:
>
> Estado de los medios. . . . . . . . . . . : medios desconectados
> Sufijo DNS espec¡fico para la conexi¢n. . : ua.mydomain.org
> Descripci¢n . . . . . . . . . . . . . . . : Adaptador ISATAP de Microsoft
> Direcci¢n f¡sica. . . . . . . . . . . . . : 00-00-00-00-00-00-00-E0
> DHCP habilitado . . . . . . . . . . . . . : no
> Configuraci¢n autom tica habilitada . . . : s¡
>
> Both IP addresses and MAC Addresses for the public and private networks was configured in Managed Computers.
>
>
> Any idea to solve this problem?
>
>
> Kind regards
>
> Darío Zapata
>
> Información Confidencial.
> La información transmitida es para el uso exclusivo de la persona o
> entidad a quien va dirigida, y puede contener información de carácter
> confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
> distinta al destinatario, cualquier revisión, retransmisión, distribución u
> otro uso de la información. Si recibió este mensaje por equivocación,
> atentamente le solicitamos eliminar la información de cualquier equipo
> de cómputo y hacerlo del conocimiento del remitente.
>
>
>
> --
> Thank you,
>
> Dmitri Chebotarov
> Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
> 223 Aquia Building, Ffx, MSN: 1B5
> Phone: (703) 993-6175
> Fax: (703) 993-3404
>
> Información Confidencial.
> La información transmitida es para el uso exclusivo de la persona o
> entidad a quien va dirigida, y puede contener información de carácter
> confidencial o privilegiado. Se prohíbe a cualquier persona o entidad
> distinta al destinatario, cualquier revisión, retransmisión, distribución u
> otro uso de la información. Si recibió este mensaje por equivocación,
> atentamente le solicitamos eliminar la información de cualquier equipo
> de cómputo y hacerlo del conocimiento del remitente.
--
Thank you,
Dmitri Chebotarov
Virtual Computing Lab Systems Engineer, TSD - Ent Servers & Messaging
223 Aquia Building, Ffx, MSN: 1B5
Phone: (703) 993-6175
Fax: (703) 993-3404