hi all,
i'm having a problem creating a base windows 7 image and i'm wondering if it is
because i'm using vcl 2.2.1 and esxi 5. the lines i think are relevant are
below. any pointers would be appreciated. thanks.
2012-06-29
12:08:30|11089|10:10|image|VMware.pm:get_vmhost_product_name(5405)|VMware
product being used on VM host vclnode01: 'VMware ESXi 5.0.0 build-623860'
2012-06-29 12:08:30|11089|10:10|image|VMware.pm:initialize(307)|product: VMware
ESXi 5.0.0 build-623860, OS object: VCL::Module::OS::Linux::UnixLab
2012-06-29 12:08:30|11089|10:10|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd call
count: 1 (hostsvc/datastore/listsummary)
2012-06-29 12:08:30|11089|10:10|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host vclnode01: vim-cmd hostsvc/datastore/listsummary
2012-06-29
12:08:30|11089|10:10|image|VMware.pm:get_datastore_info(5531)|retrieved
datastore info from VM host:
|11089|10:10|image| datastore1
|11089|10:10|image| datastore2
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|VMware.pm:_get_datastore_name(5848)|unable to
determine datastore name from path: '/vmfs/volumes/local-datastore', path does
not begin with any of the datastore paths:
|11089|10:10|image| '[datastore2]'
|11089|10:10|image| '/vmfs/volumes/datastore2'
|11089|10:10|image| '/vmfs/volumes/4fd8b523-f4b9c22c-4f9b-001ec9aa4ba2'
|11089|10:10|image| '[datastore1]'
|11089|10:10|image| '/vmfs/volumes/datastore1'
|11089|10:10|image| '/vmfs/volumes/4fcfadc7-c5fa2f69-3ffc-001ec9aa4ba2'
|11089|10:10|image| ( 0) VMware.pm, _get_datastore_name (line: 5848)
|11089|10:10|image| (-1) VMware.pm, _get_datastore_root_normal_path (line: 5685)
|11089|10:10|image| (-2) VMware.pm, _get_normal_path (line: 5775)
|11089|10:10|image| (-3) VMware.pm, get_vmx_base_directory_path (line: 2743)
|11089|10:10|image| (-4) VMware.pm, initialize (line: 313)
|11089|10:10|image| (-5) Module.pm, new (line: 207)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|VMware.pm:_get_datastore_root_normal_path(5687)|failed
to determine datastore root normal path, unable to determine datastore name:
/vmfs/volumes/local-datastore
|11089|10:10|image| ( 0) VMware.pm, _get_datastore_root_normal_path (line: 5687)
|11089|10:10|image| (-1) VMware.pm, _get_normal_path (line: 5775)
|11089|10:10|image| (-2) VMware.pm, get_vmx_base_directory_path (line: 2743)
|11089|10:10|image| (-3) VMware.pm, initialize (line: 313)
|11089|10:10|image| (-4) Module.pm, new (line: 207)
|11089|10:10|image| (-5) Module.pm, create_provisioning_object (line: 423)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|VMware.pm:_get_normal_path(5777)|unable to determine
normal path, failed to determine datastore root normal path:
/vmfs/volumes/local-datastore
|11089|10:10|image| ( 0) VMware.pm, _get_normal_path (line: 5777)
|11089|10:10|image| (-1) VMware.pm, get_vmx_base_directory_path (line: 2743)
|11089|10:10|image| (-2) VMware.pm, initialize (line: 313)
|11089|10:10|image| (-3) Module.pm, new (line: 207)
|11089|10:10|image| (-4) Module.pm, create_provisioning_object (line: 423)
|11089|10:10|image| (-5) State.pm, initialize (line: 117)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|VMware.pm:get_vmx_base_directory_path(2749)|unable
to determine the vmx base directory path, failed to convert path configured in
the VM profile to a normal path: /vmfs/volumes/local-datastore
|11089|10:10|image| ( 0) VMware.pm, get_vmx_base_directory_path (line: 2749)
|11089|10:10|image| (-1) VMware.pm, initialize (line: 313)
|11089|10:10|image| (-2) Module.pm, new (line: 207)
|11089|10:10|image| (-3) Module.pm, create_provisioning_object (line: 423)
|11089|10:10|image| (-4) State.pm, initialize (line: 117)
|11089|10:10|image| (-5) Module.pm, new (line: 207)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|VMware.pm:initialize(315)|unable to determine vmx
base directory path on VM host vclnode01
|11089|10:10|image| ( 0) VMware.pm, initialize (line: 315)
|11089|10:10|image| (-1) Module.pm, new (line: 207)
|11089|10:10|image| (-2) Module.pm, create_provisioning_object (line: 423)
|11089|10:10|image| (-3) State.pm, initialize (line: 117)
|11089|10:10|image| (-4) Module.pm, new (line: 207)
|11089|10:10|image| (-5) vcld, make_new_child (line: 564)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|Module.pm:create_provisioning_object(431)|provisioning
object could not be created, returning 0
|11089|10:10|image| ( 0) Module.pm, create_provisioning_object (line: 431)
|11089|10:10|image| (-1) State.pm, initialize (line: 117)
|11089|10:10|image| (-2) Module.pm, new (line: 207)
|11089|10:10|image| (-3) vcld, make_new_child (line: 564)
|11089|10:10|image| (-4) vcld, main (line: 346)
|11089|10:10|image| ---- WARNING ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|State.pm:initialize(118)|failed to create
provisioning object
|11089|10:10|image| ( 0) State.pm, initialize (line: 118)
|11089|10:10|image| (-1) Module.pm, new (line: 207)
|11089|10:10|image| (-2) vcld, make_new_child (line: 564)
|11089|10:10|image| (-3) vcld, main (line: 346)
2012-06-29 12:08:30|11089|10:10|image|State.pm:DESTROY(829)|VCL::image
destructor called, address: 2e4b6b8
2012-06-29
12:08:30|11089|10:10|image|utils.pm:delete_computerloadlog_reservation(6742)|removing
computerloadlog entries matching loadstate = begin
2012-06-29
12:08:30|11089|10:10|image|utils.pm:delete_computerloadlog_reservation(6789)|deleted
rows from computerloadlog for reservation id=10
2012-06-29 12:08:30|11089|10:10|image|State.pm:DESTROY(848)|number of database
handles state process created: 1
2012-06-29 12:08:30|11089|10:10|image|State.pm:DESTROY(876)|VCL::image process
duration: 2 seconds
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:get_computer_private_ip_address(1581)|attempting
to retrieve private IP address for computer: vclvm0001
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:get_computer_private_ip_address(1585)|retrieved
contents of /etc/hosts on this management node, contains 76 lines
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:get_computer_private_ip_address(1645)|returning
IP address from /etc/hosts file: 10.128.64.100
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2703)|unable to use
database handle stored in $ENV{dbh}
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2012-06-29 12:08:30|11089|10:10|image|utils.pm:is_inblockrequest(6163)|zero
rows were returned from database select
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:get_image_affiliation_name(2035)|image
owner id: 1
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:localhost)
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:retrieve_user_data(1352)|attempting
to retrieve and store data for user: user.id = '1'
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:localhost)
2012-06-29 12:08:30|11089|10:10|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2012-06-29
12:08:30|11089|10:10|image|DataStructure.pm:retrieve_user_data(1415)|data has
been retrieved for user: admin (id: 1)
2012-06-29 12:08:31|11089|10:10|image|utils.pm:mail(1268)|SUCCESS -- Sending mail
To: w...@clemson.edu,chr...@clemson.edu, PROBLEM --
10:10|image|vcld|vclvm0001>vclnode01|vmwarewin7-win7x64_base11-v0|admin
|11089|10:10|image| ---- CRITICAL ----
|11089|10:10|image| 2012-06-29
12:08:30|11089|10:10|image|vcld:make_new_child(571)|VCL::image object could not
be created and initialized
|11089|10:10|image| ( 0) vcld, make_new_child (line: 571)
|11089|10:10|image| (-1) vcld, main (line: 346)
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7253)|called from
VCL::vcld::make_new_child(572)
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7289)|parent:
parent reservation ID for this request: 10
2012-06-29 12:08:31|11089|10:10|image|utils.pm:insertloadlog(3875)|inserted
computer=5, info, VCL::vcld: switching request state to failed
2012-06-29
12:08:31|11089|10:10|image|utils.pm:update_request_state(1991)|request 10 state
updated to: failed, laststate to: image
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7313)|req=10: request
state changed: image->failed, laststate: image->image
2012-06-29 12:08:31|11089|10:10|image|utils.pm:insertloadlog(3875)|inserted
computer=5, info, VCL::vcld: request state changed to failed, laststate to image
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7323)|req=10:
computer state not specified, vclvm0001 state not changed
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7341)|req=10: log
table id=0, ending set to failed
2012-06-29 12:08:31|11089|10:10|image|utils.pm:insertloadlog(3875)|inserted
computer=5, info, VCL::vcld: process exiting
2012-06-29 12:08:31|11089|10:10|image|utils.pm:switch_state(7357)|req=10:
process exiting
2012-06-29 12:08:31|11089|10:10|image|VIM_SSH.pm:DESTROY(1685)|vim-cmd call
count: 1
2012-06-29 12:08:31|2165|vcld:REAPER(718)|VCL process exited for reservation
10, PID: 11089, signal: CHLD
2012-06-29 12:08:31|2165|10:10|failed|vcld:main(252)|request deleted
2012-06-29 12:10:02|2165|vcld:main(167)|lastcheckin time updated for management
node 2: 2012-06-29 12:10:02
--
will
