You are viewing a plain text version of this content. The canonical link for it is here.
Posted to user@vcl.apache.org by Tyler Hardesty <th...@gmail.com> on 2011/03/08 01:41:52 UTC
ssh on vmguest
I've been trying to search through the mail archive but haven't found a
solution to my problem... sorry if this was already covered and I just
didn't find it...
I'm trying to capture an image of a win xp vm and I get this error in the
log file:
|16224|3:3|image| ---- WARNING ----
|16224|3:3|image| 2011-03-07
17:18:36|16224|3:3|image|VMware.pm:capture(462)|unable to capture image, VM
vm2 is not responding to SSH
|16224|3:3|image| ( 0) VMware.pm, capture (line: 462)
|16224|3:3|image| (-1) image.pm, process (line: 165)
|16224|3:3|image| (-2) vcld, make_new_child (line: 568)
|16224|3:3|image| (-3) vcld, main (line: 346)
|16224|3:3|image| ---- WARNING ----
|16224|3:3|image| 2011-03-07
17:18:36|16224|3:3|image|image.pm:process(169)|vmwarewinxp-windowsxp7-v0
image failed to be captured by provisioning module
|16224|3:3|image| ( 0) image.pm, process (line: 169)
|16224|3:3|image| (-1) vcld, make_new_child (line: 568)
|16224|3:3|image| (-2) vcld, main (line: 346)
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
to retrieve private IP address for computer: vm2
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
contents of /etc/hosts on this management node, contains 7 lines
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
IP address from /etc/hosts file: 192.168.1.1
2011-03-07 17:18:36|16224|3:3|image|utils.pm:is_inblockrequest(6147)|zero
rows were returned from database select
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:get_image_affiliation_name(2029)|image
owner id: 1
2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:127.0.0.1)
2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1353)|attempting
to retrieve and store data for user: user.id = '1'
2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:127.0.0.1)
2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
stored in $ENV{dbh}
2011-03-07
17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1416)|data has
been retrieved for user: admin (id: 1)
2011-03-07 17:18:36|16224|3:3|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, PROBLEM -- 3:3|image|image.pm
|vm2>localvmhost|vmwarewinxp-windowsxp7-v0|admin
|16224|3:3|image| ---- CRITICAL ----
|16224|3:3|image| 2011-03-07
17:18:36|16224|3:3|image|image.pm:reservation_failed(393)|vmwarewinxp-windowsxp7-v0
image creation failed
|16224|3:3|image| ( 0) image.pm, reservation_failed (line: 393)
|16224|3:3|image| (-1) image.pm, process (line: 170)
|16224|3:3|image| (-2) vcld, make_new_child (line: 568)
|16224|3:3|image| (-3) vcld, main (line: 346)
I've run the cygwin on the win xp vm and it was successfull... copying and
pasting that command it shows at the end of the setup allowed me to ssh into
it without having to put in a passwd or whatever so then I tried to ssh to
it from my management node and I get:
[thardesty@vcl ~]$ ssh xxx.xx.xxx.xxx
Warning: Permanently added 'xxx.xx.xxx.xxx' (RSA) to the list of known
hosts.
Permission denied (publickey,keyboard-interactive).
I can't seem to find what I am doing wrong so any help would be great!!
-Tyler Hardesty
Re: ssh on vmguest
Posted by Tyler Hardesty <th...@gmail.com>.
Andy thanks, that was exactly right, I had some typos in my hosts file...
but now I am encountering some different warnings that are preventing the
image from being captured:
|16014|14:14|image| ---- WARNING ----
|16014|14:14|image| 2011-03-15
16:00:38|16014|14:14|image|VMware.pm:copy_vmdk(4750)|destination vmdk file
path already exists on VM host localvmhost: /var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk
|16014|14:14|image| ( 0) VMware.pm, copy_vmdk (line: 4750)
|16014|14:14|image| (-1) VMware.pm, capture (line: 597)
|16014|14:14|image| (-2) image.pm, process (line: 165)
|16014|14:14|image| (-3) vcld, make_new_child (line: 568)
|16014|14:14|image| (-4) vcld, main (line: 346)
|16014|14:14|image| ---- WARNING ----
|16014|14:14|image| 2011-03-15
16:00:38|16014|14:14|image|VMware.pm:capture(601)|failed to copy the vmdk
files after the VM was powered off: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk' -->
'/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk'
|16014|14:14|image| ( 0) VMware.pm, capture (line: 601)
|16014|14:14|image| (-1) image.pm, process (line: 165)
|16014|14:14|image| (-2) vcld, make_new_child (line: 568)
|16014|14:14|image| (-3) vcld, main (line: 346)
2011-03-15
16:00:38|16014|14:14|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk' exists on
localvmhost, files: 1, directories: 0, links: 0
2011-03-15 16:00:39|16014|14:14|image|Linux.pm:file_exists(1368)|file or
directory does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vm2_1/vm2.vmdk'
2011-03-15 16:00:39|16014|14:14|image|VMware.pm:rename_vmdk(4995)|attempting
to rename vmdk: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk' -->
'/var/lib/vmware/Virtual Machines/vm2_1/vm2.vmdk'
2011-03-15
16:00:39|16014|14:14|image|Linux.pm:create_directory(1502)|directory already
exists on localvmhost: '/var/lib/vmware/Virtual Machines/vm2_1'
2011-03-15
16:00:39|16014|14:14|image|VMware.pm:rename_vmdk(5018)|'move_virtual_disk'
subroutine has not been implemented by the API:
VCL::Module::Provisioning::VMware::VIM_SSH
2011-03-15 16:00:39|16014|14:14|image|VMware.pm:rename_vmdk(5025)|attempting
to rename vmdk file using vmware-vdiskmanager: /var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk -->
/var/lib/vmware/Virtual Machines/vm2_1/vm2.vmdk
2011-03-15 16:00:39|16014|14:14|image|VMware.pm:rename_vmdk(5032)|renamed
vmdk file by executing 'vmware-vdiskmanager' command on VM host:
|16014|14:14|image| command: vmware-vdiskmanager -n "/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0/vmwarewinxp-vm2xp18-v0.vmdk"
"/var/lib/vmware/Virtual Machines/vm2_1/vm2.vmdk"
|16014|14:14|image| output: Renaming completed successfully.
2011-03-15 16:00:39|16014|14:14|image|VMware.pm:capture(690)|attempting to
delete directory where renamed vmdk resided before reverting the name back
to the original: /var/lib/vmware/Virtual Machines/vmwarewinxp-vm2xp18-v0
2011-03-15 16:00:39|16014|14:14|image|Linux.pm:delete_file(1437)|deleted
'/var/lib/vmware/Virtual Machines/vmwarewinxp-vm2xp18-v0' on localvmhost
2011-03-15 16:00:40|16014|14:14|image|Linux.pm:file_exists(1368)|file or
directory does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0'
2011-03-15 16:00:40|16014|14:14|image|Linux.pm:delete_file(1451)|confirmed
file does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-vm2xp18-v0'
2011-03-15 16:00:40|16014|14:14|image|VMware.pm:capture(696)|attempting to
power the VM back on so that it can be captured again
2011-03-15 16:00:40|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 10 (vmsvc/getallvms)
2011-03-15 16:00:41|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host localvmhost: vmware-vim-cmd vmsvc/getallvms
2011-03-15 16:00:41|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 11 (vmsvc/power.on 48)
2011-03-15 16:00:43|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host localvmhost: vmware-vim-cmd vmsvc/power.on 48
2011-03-15 16:00:43|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 12 (vmsvc/getallvms)
2011-03-15 16:00:46|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host localvmhost: vmware-vim-cmd vmsvc/getallvms
2011-03-15 16:00:46|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 13 (vmsvc/get.tasklist 48)
2011-03-15 16:00:48|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host localvmhost: vmware-vim-cmd vmsvc/get.tasklist 48
2011-03-15
16:00:48|16014|14:14|image|VIM_SSH.pm:_wait_for_task(748)|checking status of
task: haTask-48-vim.VirtualMachine.powerOn-50331948
2011-03-15 16:00:48|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(193)|vim-cmd
call count: 14 (vimsvc/task_info
haTask-48-vim.VirtualMachine.powerOn-50331948)
2011-03-15 16:00:49|16014|14:14|image|VIM_SSH.pm:_run_vim_cmd(208)|executed
command on VM host localvmhost: vmware-vim-cmd vimsvc/task_info
haTask-48-vim.VirtualMachine.powerOn-50331948
2011-03-15 16:00:49|16014|14:14|image|VIM_SSH.pm:_wait_for_task(771)|task
completed successfully: haTask-48-vim.VirtualMachine.powerOn-50331948
2011-03-15 16:00:49|16014|14:14|image|VIM_SSH.pm:vm_power_on(970)|powered on
VM: /var/lib/vmware/Virtual Machines/vm2_1/vm2.vmx
|16014|14:14|image| ---- WARNING ----
|16014|14:14|image| 2011-03-15
16:00:49|16014|14:14|image|image.pm:process(169)|vmwarewinxp-vm2xp18-v0
image failed to be captured by provisioning module
|16014|14:14|image| ( 0) image.pm, process (line: 169)
|16014|14:14|image| (-1) vcld, make_new_child (line: 568)
|16014|14:14|image| (-2) vcld, main (line: 346)
2011-03-15
16:00:49|16014|14:14|image|DataStructure.pm:get_computer_private_ip_address(1558)|returning
private IP address previously retrieved from /etc/hosts: 192.168.128.0
2011-03-15 16:00:49|16014|14:14|image|utils.pm:is_inblockrequest(6147)|zero
rows were returned from database select
2011-03-15
16:00:49|16014|14:14|image|DataStructure.pm:get_image_affiliation_name(2029)|image
owner id: 1
2011-03-15 16:00:49|16014|14:14|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:127.0.0.1)
2011-03-15 16:00:49|16014|14:14|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-15
16:00:50|16014|14:14|image|DataStructure.pm:retrieve_user_data(1353)|attempting
to retrieve and store data for user: user.id = '1'
2011-03-15 16:00:50|16014|14:14|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:127.0.0.1)
2011-03-15 16:00:50|16014|14:14|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-15
16:00:50|16014|14:14|image|DataStructure.pm:retrieve_user_data(1416)|data
has been retrieved for user: admin (id: 1)
2011-03-15 16:00:51|16014|14:14|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, PROBLEM -- 14:14|image|image.pm
|vm2>localvmhost|vmwarewinxp-vm2xp18-v0|admin
|16014|14:14|image| ---- CRITICAL ----
|16014|14:14|image| 2011-03-15
16:00:49|16014|14:14|image|image.pm:reservation_failed(393)|vmwarewinxp-vm2xp18-v0
image creation failed
|16014|14:14|image| ( 0) image.pm, reservation_failed (line: 393)
|16014|14:14|image| (-1) image.pm, process (line: 170)
|16014|14:14|image| (-2) vcld, make_new_child (line: 568)
|16014|14:14|image| (-3) vcld, main (line: 346)
2011-03-15 16:00:51|16014|14:14|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: root@localhost, VCL -- NOTICE DELAY Image Creation vm2xp
2011-03-15 16:00:51|16014|14:14|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, VCL -- NOTICE FAILED Image Creation
vm2xp
2011-03-15 16:00:51|16014|14:14|image|utils.pm:update_request_state(1991)|request
14 state updated to: maintenance, laststate to: image
2011-03-15 16:00:51|16014|14:14|image|image.pm:reservation_failed(445)|request
state set to maintenance, laststate to image
2011-03-15 16:00:51|16014|14:14|image|utils.pm:update_computer_state(2033)|computer
3 state updated to: maintenance
2011-03-15 16:00:51|16014|14:14|image|image.pm:reservation_failed(453)|vm2
state set to maintenance
2011-03-15 16:00:51|16014|14:14|image|image.pm:
reservation_failed(460)|exiting
2011-03-15 16:00:51|16014|14:14|image|State.pm:DESTROY(839)|VCL::image
destructor called, address: a795030
2011-03-15 16:00:51|16014|14:14|image|utils.pm:delete_computerloadlog_reservation(6726)|removing
computerloadlog entries matching loadstate = begin
2011-03-15 16:00:51|16014|14:14|image|utils.pm:delete_computerloadlog_reservation(6773)|deleted
rows from computerloadlog for reservation id=14
2011-03-15 16:00:51|16014|14:14|image|State.pm:DESTROY(858)|number of
database handles state process created: 3
2011-03-15 16:00:51|16014|14:14|image|State.pm:DESTROY(886)|VCL::image
process duration: 503 seconds
2011-03-15 16:00:51|16014|14:14|image|VIM_SSH.pm:DESTROY(1685)|vim-cmd call
count: 14
2011-03-15 16:00:51|1183|vcld:REAPER(718)|VCL process exited for reservation
14, PID: 16014, signal: CHLD
Those don't seem to be a lot to go off of so I'm not quite sure how to fix
it... I've checked the path that it says already exists, but it doesn't
exist when I look for it so I don't know!?
Thanks again,
-Tyler
On Thu, Mar 10, 2011 at 9:08 AM, Andy Kurth <an...@ncsu.edu> wrote:
> Hello,
> The code first attempts to run nmap to check if the SSH ports are even
> open. Based on your log output, this is where it is failing:
>
> "vm2 is NOT responding to SSH, ports 22 or 24 are both closed"
>
> Try running this command:
> /usr/bin/nmap vm2 -P0 -p 22 -T Aggressive
>
> If you get a "Failed to resolve given hostname/IP" error then there is a
> problem with your hosts file. Make sure vm2's private IP address has been
> added to /etc/hosts/. Run "host vm2". It should display:
> vm2 has address xxx.xxx.xxx.xxx
>
> If the SSH port is open you should see "1 host up". If this is the case,
> try running the following command:
> /usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vm2 'echo testing'
>
> Does this command succeed?
>
> -Andy
>
>
>
Re: ssh on vmguest
Posted by Andy Kurth <an...@ncsu.edu>.
Hello,
The code first attempts to run nmap to check if the SSH ports are even
open. Based on your log output, this is where it is failing:
"vm2 is NOT responding to SSH, ports 22 or 24 are both closed"
Try running this command:
/usr/bin/nmap vm2 -P0 -p 22 -T Aggressive
If you get a "Failed to resolve given hostname/IP" error then there is a
problem with your hosts file. Make sure vm2's private IP address has
been added to /etc/hosts/. Run "host vm2". It should display:
vm2 has address xxx.xxx.xxx.xxx
If the SSH port is open you should see "1 host up". If this is the
case, try running the following command:
/usr/bin/ssh -i /etc/vcl/vcl.key -l root -p 22 -x vm2 'echo testing'
Does this command succeed?
-Andy
On 3/8/2011 4:58 PM, Tyler Hardesty wrote:
> Hey thanks!
>
> adding those lines helped solve my permission denied problem but
> unfortunately I still can't create the base image... It keeps telling me
> that it is not responding to ssh (It also looks like Jay Diener-Brazelle
> had this problem that he posted at the end of Feb 2011 but I don't see a
> solution reply to his either)
>
> management node:
>
> [root@vcl vcl]# /usr/local/vcl/bin/vcld -setup
> VCL Management Node Setup
> ----------------------------------------------------------------------------
> Select a module to configure:
> 1. VCL Base Module
> 2. VCL Image State Module
> 3. Windows OS Module
>
> [vcld]
> Make a selection (1-3, 'c' to cancel): 2
> ----------------------------------------------------------------------------
> Choose an operation:
> 1. Capture Base Image
>
> [vcld/Image]
> Make a selection (1, 'c' to cancel): 1
>
> Enter the VCL login name or ID of the user who will own the image: [admin]:
>
> User who will own the image: admin (ID: 1)
>
> Enter the hostname or IP address of the computer to be captured: ('c' to
> cancel): vm2
>
> Computer to be captured: vm2 (ID: 3)
> Provisioning module: provisioning_vmware
> Install type: vmware
>
> Select the OS to be captured (install type: vmware):
> 1. Generic Linux (VMware)
> 2. Windows 2003 Server (VMware)
> 3. Windows 7 (VMware)
> 4. Windows Server 2008 (VMware)
> 5. Windows Vista (VMware)
> 6. Windows XP (VMware)
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-6, 'c' to cancel): 6
>
> Selected OS: Windows XP (VMware)
>
> Image architecture:
> 1. x86
> 2. x86_64
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 1
>
> Image architecture: x86
>
> Use Sysprep:
> 1. Yes
> 2. No
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 2
>
> Use Sysprep: No
>
> Enter the name of the image to be captured: ('c' to cancel): win_xp
>
> Added new image to database: 'win_xp'
> image.name <http://image.name>: vmwarewinxp-win_xp14-v0
> image.id <http://image.id>: 14
> imagerevision.id <http://imagerevision.id>: 14
> imagemeta.id <http://imagemeta.id>: 12
> resource.id <http://resource.id>: 24
>
> ----------------------------------------------------------------------------
> Inserted imaging request to the database:
> request ID: 10
> reservation ID: 10
>
> This process will now display the contents of the vcld.log file if the vcld
> daemon is running. If you do not see many lines of additional output,
> exit this
> process, start the vcld daemon, and monitor the image capture process by
> running
> the command:
> tail -f /var/log/vcld.log | grep '10:10'
>
> ----------------------------------------------------------------------------
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8343)|inserted new
> reload request into request table, request id=10
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8374)|inserted new
> reload request into reservation table, reservation id=10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8923)|computerloadlog
> 'begin' entry does NOT exist for reservation 10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:run_command(8995)|executed command:
> pgrep -fl 'vcld [0-9]+:10 ', pid: 20517, exit status: 1, output:
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:is_management_node_process_running(9177)|process
> is NOT running, identifier: 'vcld [0-9]+:10 '
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8944)|reservation
> is NOT currently being processed
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(278)|reservation 10 is
> NOT already being processed
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:get_request_info(4510)|standalone
> affiliation found: Local
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(283)|retrieved request
> information from database
> 2011-03-08
> 14:40:05|13551|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: pending, laststate to: image
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:insertloadlog(3875)|inserted
> computer=3, begin, beginning to process, state is image
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(515)|loaded
> VCL::image module
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(539)|current
> number of forked kids: 1
> 2011-03-08 14:40:05|20532|10:10|image|vcld:make_new_child(555)|vcld
> environment variable set to 0 for this process
> 2011-03-08 14:40:05|20532|10:10|image|Module.pm:new(161)|VCL::image
> object created for state image, address: 9a54b24
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:initialize(86)|obtained a
> database handle for this state process, stored as $ENV{dbh}
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:check_image_os(772)|no
> corrections need to be made to image OS: vmwarewinxp
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7082)|reservation count:
> 1
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7101)|PARENTIMAGE: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7102)|SUBIMAGE: 0
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7120)|renamed
> process to 'vcld 10:10 image vm2>localvmhost vmwarewinxp-win_xp14-v0 admin'
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(267)|VCL::Module::OS::Windows::Version_5::XP
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Windows::Version_5::XP
> object created for image vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(273)|VCL::Module::OS::Windows::Version_5::XP
> OS object created for vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(330)|VCL::Module::OS::Linux::ManagementNode
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::ManagementNode
> object created for image <not set>, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated: $self->request_data->{reservation}{0}{computer}{hostname}
> |20532|10:10|image| computer_hostname = vcl.tylerhardesty.info
> <http://vcl.tylerhardesty.info>
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated: $self->request_data->{reservation}{0}{computer}{NODENAME}
> |20532|10:10|image| computer_node_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{SHORTNAME}
> |20532|10:10|image| computer_short_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{IPaddress}
> |20532|10:10|image| computer_ip_address = 144.38.205.249
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(335)|VCL::Module::OS::Linux::ManagementNode
> OS object created, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_provisioning_object(389)|VCL::Module::Provisioning::VMware::VMware
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VMware
> object created for computer vm2, address: 9f75548
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:initialize(230)|initializing
> VCL::Module::Provisioning::VMware::VMware object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:initialize(249)|attempting to
> create OS object for the image currently loaded on the VM host: localvmhost
> |20532|10:10|image| image name: noimage
> |20532|10:10|image| OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1229)|attempting
> to load VM host OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1235)|VM host
> OS module loaded: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::UnixLab
> object created for image noimage, address: a18b8a8
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1241)|VM host
> OS object created: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|OS.pm:is_ssh_responding(401)|localvmhost is
> responding to SSH, port 22: open, port 24: closed
> 2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(254)|OS on VM
> host localvmhost will be controlled using a
> VCL::Module::OS::Linux::UnixLab OS object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module:
> VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
> VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::vSphere_SDK
> object created for computer vm2, address: a18e41c
> 2011-03-08
> 14:40:05|20532|10:10|image|vSphere_SDK.pm:initialize(1826)|vSphere SDK
> for Perl does not appear to be installed on this managment node, unable
> to load VMware vSphere SDK Perl modules
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1310)|API
> object could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
> VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VIM_SSH
> object created for computer vm2, address: a1a0118
> 2011-03-08 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(138)|VIM
> executable available on VM host: vmware-vim-cmd
> 2011-03-08
> 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH
> object initialized
> 2011-03-08
> 14:40:06|20532|10:10|image|VMware.pm:get_vmhost_api_object(1317)|created
> API object: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(278)|VMware
> on VM host localvmhost will be controlled using vim-cmd via SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(293)|VMware
> OS and API objects created for VM host localvmhost:
> |20532|10:10|image| VM host OS object type: VCL::Module::OS::Linux::UnixLab
> |20532|10:10|image| VMware API object type:
> VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmhost_product_name(5374)|VMware product
> being used on VM host localvmhost: 'VMware Server 2.0.2 build-203138'
> 2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(307)|product:
> VMware Server 2.0.2 build-203138, OS object: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmx_base_directory_path(2736)|determined
> vmx base directory path: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(324)|not
> checking if vmdk base directory exists because it is the same as the vmx
> base directory: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Module.pm:create_provisioning_object(395)|VCL::Module::Provisioning::VMware::VMware
> provisioner object created for vm2, address: 9f75548
> 2011-03-08 14:40:08|20532|10:10|image|State.pm:initialize(136)|returning 1
> 2011-03-08
> 14:40:08|20532|10:10|image|vcld:make_new_child(565)|VCL::image object
> created and initialized
> 2011-03-08 14:40:08|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, VCL IMAGE Creation Started:
> vmwarewinxp-win_xp14-v0
> 2011-03-08 14:40:08|20532|10:10|image|Linux.pm:file_exists(1368)|file or
> directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3906)|image does
> not exist in the non-persistent directory on the VM host
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:is_repository_mounted_on_vmhost(5454)|image
> repository is mounted on VM host localvmhost: /var/lib/vmware/Virtual
> Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3917)|checking if
> vmdk file exists in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|Linux.pm:file_exists(1368)|file or
> directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08
> 14:40:09|20532|10:10|image|VMware.pm:does_image_exist(3923)|vmdk file
> does not exist in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(148)|image
> vmwarewinxp-win_xp14-v0 does not exist in the repository
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated: $self->request_data->{reservation}{10}{image}{lastupdate}
> |20532|10:10|image| image_lastupdate = 2011-03-08 14:40:09
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{10}{imagerevision}{datecreated}
> |20532|10:10|image| imagerevision_date_created = 2011-03-08 14:40:09
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(164)|calling
> provisioning module's capture() subroutine
> 2011-03-08 14:40:09|20532|10:10|image|OS.pm:is_ssh_responding(386)|vm2
> is NOT responding to SSH, ports 22 or 24 are both closed
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|VMware.pm:capture(462)|unable to capture
> image, VM vm2 is not responding to SSH
> |20532|10:10|image| ( 0) VMware.pm, capture (line: 462)
> |20532|10:10|image| (-1) image.pm <http://image.pm>, process (line: 165)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:process(169)|vmwarewinxp-win_xp14-v0
> image failed to be captured by provisioning module
> |20532|10:10|image| ( 0) image.pm <http://image.pm>, process (line: 169)
> |20532|10:10|image| (-1) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-2) vcld, main (line: 346)
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> to retrieve private IP address for computer: vm2
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> contents of /etc/hosts on this management node, contains 7 lines
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> IP address from /etc/hosts file: 192.168.1.1
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:is_inblockrequest(6147)|zero rows
> were returned from database select
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> owner id: 1
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
> requested (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:127.0.0.1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> to retrieve and store data for user: user.id <http://user.id> = '1'
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
> requested (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:127.0.0.1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1416)|data has
> been retrieved for user: admin (id: 1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, PROBLEM -- 10:10|image|image.pm
> <http://image.pm>|vm2>localvmhost|vmwarewinxp-win_xp14-v0|admin
> |20532|10:10|image| ---- CRITICAL ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(393)|vmwarewinxp-win_xp14-v0
> image creation failed
> |20532|10:10|image| ( 0) image.pm <http://image.pm>, reservation_failed
> (line: 393)
> |20532|10:10|image| (-1) image.pm <http://image.pm>, process (line: 170)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation win_xp
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, VCL -- NOTICE FAILED Image
> Creation win_xp
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: maintenance, laststate to: image
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(445)|request
> state set to maintenance, laststate to image
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:update_computer_state(2033)|computer
> 3 state updated to: maintenance
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(453)|vm2 state
> set to maintenance
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(460)|exiting
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(839)|VCL::image
> destructor called, address: 9a54b24
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6726)|removing
> computerloadlog entries matching loadstate = begin
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6773)|deleted
> rows from computerloadlog for reservation id=10
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(858)|number of
> database handles state process created: 3
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(886)|VCL::image
> process duration: 4 seconds
> 2011-03-08 14:40:09|13551|vcld:REAPER(718)|VCL process exited for
> reservation 10, PID: 20532, signal: CHLD
> 2011-03-08 14:42:10|13551|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-03-08 14:42:10
>
>
> On Tue, Mar 8, 2011 at 2:00 PM, James O'Dell <jodell@fullerton.edu
> <ma...@fullerton.edu>> wrote:
>
> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA1
>
> Hi,
>
> I've gone through and compared your ssh debug info with mine.
>
> It looks like your '/etc/ssh/ssh_config' is different than
> mine. I added the following to the end of the file when I built
> vcl:
>
> > IdentityFile /opt/vcl/etc/vcl/vcl.key
> > StrictHostKeyChecking no
>
>
> I think this reflects the problem. You get:
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /root/.ssh/identity ((nil))
> > debug2: key: /root/.ssh/id_rsa ((nil))
> > debug2: key: /root/.ssh/id_dsa ((nil))
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
>
> I get
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /opt/vcl/etc/vcl/vcl.key (0x2ad12fbccc30)
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
>
>
> Also, where you get
> > Warning: Permanently added '144.38.205.252' (RSA) to the list of
> known
>
> I get
> > debug1: Host 'vclvm010511' is known and matches the RSA host key.
> > debug1: Found key in /root/.ssh/known_hosts:856
>
>
> Here's where it really diverges. You have
> > debug1: Next authentication method: publickey
> > debug1: Trying private key: /root/.ssh/identity
> > debug3: no such identity: /root/.ssh/identity
> > debug1: Trying private key: /root/.ssh/id_rsa
> > debug3: no such identity: /root/.ssh/id_rsa
> > debug1: Trying private key: /root/.ssh/id_dsa
> > debug3: no such identity: /root/.ssh/id_dsa
> > debug2: we did not send a packet, disable method
> > debug3: authmethod_lookup keyboard-interactive
> > debug3: remaining preferred: password
> > debug3: authmethod_is_enabled keyboard-interactive
> > debug1: Next authentication method: keyboard-interactive
> > debug2: userauth_kbdint
> > debug2: we sent a keyboard-interactive packet, wait for reply
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
> > debug3: userauth_kbdint: disable: no info_req_seen
> > debug2: we did not send a packet, disable method
> > debug1: No more authentication methods to try.
>
> I have
> > debug1: Next authentication method: publickey
> > debug1: Offering public key: /opt/vcl/etc/vcl/vcl.key
> > debug3: send_pubkey_test
> > debug2: we sent a publickey packet, wait for reply
> > debug1: Server accepts key: pkalg ssh-rsa blen 149
>
>
>
> Hope this helps,
>
> __Jim
>
Re: ssh on vmguest
Posted by Tyler Hardesty <th...@gmail.com>.
If this can't get figured out... Is there another way I can create an image
to use with Apache VCL... what does Apache VCL look for, just a .img??
On Wed, Mar 9, 2011 at 6:20 AM, Jay Diener-Brazelle <ja...@vcu.edu>wrote:
> I was still stuck at that point. But unfortunately my management has
> pulled the plug on the VCL project at my university.
>
>
> On 3/8/2011 4:58 PM, Tyler Hardesty wrote:
>
> Hey thanks!
>
> adding those lines helped solve my permission denied problem but
> unfortunately I still can't create the base image... It keeps telling me
> that it is not responding to ssh (It also looks like Jay Diener-Brazelle had
> this problem that he posted at the end of Feb 2011 but I don't see a
> solution reply to his either)
>
> management node:
>
> [root@vcl vcl]# /usr/local/vcl/bin/vcld -setup
> VCL Management Node Setup
>
> ----------------------------------------------------------------------------
> Select a module to configure:
> 1. VCL Base Module
> 2. VCL Image State Module
> 3. Windows OS Module
>
> [vcld]
> Make a selection (1-3, 'c' to cancel): 2
>
> ----------------------------------------------------------------------------
> Choose an operation:
> 1. Capture Base Image
>
> [vcld/Image]
> Make a selection (1, 'c' to cancel): 1
>
> Enter the VCL login name or ID of the user who will own the image: [admin]:
>
>
> User who will own the image: admin (ID: 1)
>
> Enter the hostname or IP address of the computer to be captured: ('c' to
> cancel): vm2
>
> Computer to be captured: vm2 (ID: 3)
> Provisioning module: provisioning_vmware
> Install type: vmware
>
> Select the OS to be captured (install type: vmware):
> 1. Generic Linux (VMware)
> 2. Windows 2003 Server (VMware)
> 3. Windows 7 (VMware)
> 4. Windows Server 2008 (VMware)
> 5. Windows Vista (VMware)
> 6. Windows XP (VMware)
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-6, 'c' to cancel): 6
>
> Selected OS: Windows XP (VMware)
>
> Image architecture:
> 1. x86
> 2. x86_64
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 1
>
> Image architecture: x86
>
> Use Sysprep:
> 1. Yes
> 2. No
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 2
>
> Use Sysprep: No
>
> Enter the name of the image to be captured: ('c' to cancel): win_xp
>
> Added new image to database: 'win_xp'
> image.name: vmwarewinxp-win_xp14-v0
> image.id: 14
> imagerevision.id: 14
> imagemeta.id: 12
> resource.id: 24
>
>
> ----------------------------------------------------------------------------
> Inserted imaging request to the database:
> request ID: 10
> reservation ID: 10
>
> This process will now display the contents of the vcld.log file if the vcld
> daemon is running. If you do not see many lines of additional output, exit
> this
> process, start the vcld daemon, and monitor the image capture process by
> running
> the command:
> tail -f /var/log/vcld.log | grep '10:10'
>
>
> ----------------------------------------------------------------------------
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8343)|inserted new
> reload request into request table, request id=10
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8374)|inserted new
> reload request into reservation table, reservation id=10
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8923)|computerloadlog
> 'begin' entry does NOT exist for reservation 10
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:run_command(8995)|executed
> command: pgrep -fl 'vcld [0-9]+:10 ', pid: 20517, exit status: 1, output:
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:is_management_node_process_running(9177)|process
> is NOT running, identifier: 'vcld [0-9]+:10 '
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8944)|reservation
> is NOT currently being processed
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(278)|reservation 10 is NOT
> already being processed
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:get_request_info(4510)|standalone
> affiliation found: Local
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(283)|retrieved request
> information from database
> 2011-03-08
> 14:40:05|13551|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: pending, laststate to: image
> 2011-03-08 14:40:05|13551|10:10|image|utils.pm:insertloadlog(3875)|inserted
> computer=3, begin, beginning to process, state is image
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(515)|loaded
> VCL::image module
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(539)|current
> number of forked kids: 1
> 2011-03-08 14:40:05|20532|10:10|image|vcld:make_new_child(555)|vcld
> environment variable set to 0 for this process
> 2011-03-08 14:40:05|20532|10:10|image|Module.pm:new(161)|VCL::image object
> created for state image, address: 9a54b24
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:initialize(86)|obtained a
> database handle for this state process, stored as $ENV{dbh}
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:check_image_os(772)|no
> corrections need to be made to image OS: vmwarewinxp
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7082)|reservation
> count: 1
> 2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7101)|PARENTIMAGE:
> 1
> 2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7102)|SUBIMAGE:
> 0
> 2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7120)|renamed
> process to 'vcld 10:10 image vm2>localvmhost vmwarewinxp-win_xp14-v0 admin'
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(267)|VCL::Module::OS::Windows::Version_5::XP
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Windows::Version_5::XP
> object created for image vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(273)|VCL::Module::OS::Windows::Version_5::XP
> OS object created for vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(330)|VCL::Module::OS::Linux::ManagementNode
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::ManagementNode
> object created for image <not set>, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{0}{computer}{hostname}
> |20532|10:10|image| computer_hostname = vcl.tylerhardesty.info
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{0}{computer}{NODENAME}
> |20532|10:10|image| computer_node_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{0}{computer}{SHORTNAME}
> |20532|10:10|image| computer_short_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{0}{computer}{IPaddress}
> |20532|10:10|image| computer_ip_address = 144.38.205.249
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(335)|VCL::Module::OS::Linux::ManagementNode
> OS object created, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_provisioning_object(389)|VCL::Module::Provisioning::VMware::VMware
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VMware
> object created for computer vm2, address: 9f75548
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:initialize(230)|initializing
> VCL::Module::Provisioning::VMware::VMware object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
> for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
> for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(249)|attempting
> to create OS object for the image currently loaded on the VM host:
> localvmhost
> |20532|10:10|image| image name: noimage
> |20532|10:10|image| OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
> for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
> for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1229)|attempting
> to load VM host OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1235)|VM host OS
> module loaded: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::UnixLab
> object created for image noimage, address: a18b8a8
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1241)|VM host OS
> object created: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|OS.pm:is_ssh_responding(401)|localvmhost is
> responding to SSH, port 22: open, port 24: closed
> 2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(254)|OS on VM
> host localvmhost will be controlled using a VCL::Module::OS::Linux::UnixLab
> OS object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
> for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
> for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module:
> VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
> VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::vSphere_SDK
> object created for computer vm2, address: a18e41c
> 2011-03-08
> 14:40:05|20532|10:10|image|vSphere_SDK.pm:initialize(1826)|vSphere SDK for
> Perl does not appear to be installed on this managment node, unable to load
> VMware vSphere SDK Perl modules
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1310)|API object
> could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
> argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
> for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
> for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
> VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VIM_SSH
> object created for computer vm2, address: a1a0118
> 2011-03-08 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(138)|VIM
> executable available on VM host: vmware-vim-cmd
> 2011-03-08
> 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH
> object initialized
> 2011-03-08
> 14:40:06|20532|10:10|image|VMware.pm:get_vmhost_api_object(1317)|created API
> object: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(278)|VMware on
> VM host localvmhost will be controlled using vim-cmd via SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(293)|VMware OS
> and API objects created for VM host localvmhost:
> |20532|10:10|image| VM host OS object type: VCL::Module::OS::Linux::UnixLab
> |20532|10:10|image| VMware API object type:
> VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmhost_product_name(5374)|VMware
> product being used on VM host localvmhost: 'VMware Server 2.0.2
> build-203138'
> 2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(307)|product:
> VMware Server 2.0.2 build-203138, OS object: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmx_base_directory_path(2736)|determined
> vmx base directory path: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(324)|not
> checking if vmdk base directory exists because it is the same as the vmx
> base directory: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Module.pm:create_provisioning_object(395)|VCL::Module::Provisioning::VMware::VMware
> provisioner object created for vm2, address: 9f75548
> 2011-03-08 14:40:08|20532|10:10|image|State.pm:initialize(136)|returning 1
> 2011-03-08 14:40:08|20532|10:10|image|vcld:make_new_child(565)|VCL::image
> object created and initialized
> 2011-03-08 14:40:08|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info, VCL IMAGE Creation Started:
> vmwarewinxp-win_xp14-v0
> 2011-03-08 14:40:08|20532|10:10|image|Linux.pm:file_exists(1368)|file or
> directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3906)|image does not
> exist in the non-persistent directory on the VM host
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:is_repository_mounted_on_vmhost(5454)|image
> repository is mounted on VM host localvmhost: /var/lib/vmware/Virtual
> Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3917)|checking if vmdk
> file exists in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|Linux.pm:file_exists(1368)|file or
> directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08 14:40:09|20532|10:10|image|VMware.pm:does_image_exist(3923)|vmdk
> file does not exist in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(148)|image
> vmwarewinxp-win_xp14-v0 does not exist in the repository
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{10}{image}{lastupdate}
> |20532|10:10|image| image_lastupdate = 2011-03-08 14:40:09
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data structure
> updated: $self->request_data->{reservation}{10}{imagerevision}{datecreated}
> |20532|10:10|image| imagerevision_date_created = 2011-03-08 14:40:09
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(164)|calling
> provisioning module's capture() subroutine
> 2011-03-08 14:40:09|20532|10:10|image|OS.pm:is_ssh_responding(386)|vm2 is
> NOT responding to SSH, ports 22 or 24 are both closed
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|VMware.pm:capture(462)|unable to capture image,
> VM vm2 is not responding to SSH
> |20532|10:10|image| ( 0) VMware.pm, capture (line: 462)
> |20532|10:10|image| (-1) image.pm, process (line: 165)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(169)|vmwarewinxp-win_xp14-v0
> image failed to be captured by provisioning module
> |20532|10:10|image| ( 0) image.pm, process (line: 169)
> |20532|10:10|image| (-1) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-2) vcld, main (line: 346)
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> to retrieve private IP address for computer: vm2
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> contents of /etc/hosts on this management node, contains 7 lines
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> IP address from /etc/hosts file: 192.168.1.1
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:is_inblockrequest(6147)|zero
> rows were returned from database select
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> owner id: 1
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
> requested (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:127.0.0.1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> to retrieve and store data for user: user.id = '1'
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
> requested (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:127.0.0.1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1416)|data
> has been retrieved for user: admin (id: 1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info, PROBLEM -- 10:10|image|
> image.pm|vm2>localvmhost|vmwarewinxp-win_xp14-v0|admin
> |20532|10:10|image| ---- CRITICAL ----
> |20532|10:10|image| 2011-03-08 14:40:09|20532|10:10|image|image.pm:reservation_failed(393)|vmwarewinxp-win_xp14-v0
> image creation failed
> |20532|10:10|image| ( 0) image.pm, reservation_failed (line: 393)
> |20532|10:10|image| (-1) image.pm, process (line: 170)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation win_xp
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info, VCL -- NOTICE FAILED Image
> Creation win_xp
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: maintenance, laststate to: image
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:reservation_failed(445)|request
> state set to maintenance, laststate to image
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:update_computer_state(2033)|computer
> 3 state updated to: maintenance
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:reservation_failed(453)|vm2
> state set to maintenance
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:
> reservation_failed(460)|exiting
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(839)|VCL::image
> destructor called, address: 9a54b24
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6726)|removing
> computerloadlog entries matching loadstate = begin
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6773)|deleted
> rows from computerloadlog for reservation id=10
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(858)|number of
> database handles state process created: 3
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(886)|VCL::image
> process duration: 4 seconds
> 2011-03-08 14:40:09|13551|vcld:REAPER(718)|VCL process exited for
> reservation 10, PID: 20532, signal: CHLD
> 2011-03-08 14:42:10|13551|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-03-08 14:42:10
>
>
Re: ssh on vmguest
Posted by Jay Diener-Brazelle <ja...@vcu.edu>.
I was still stuck at that point. But unfortunately my management has
pulled the plug on the VCL project at my university.
On 3/8/2011 4:58 PM, Tyler Hardesty wrote:
> Hey thanks!
>
> adding those lines helped solve my permission denied problem but
> unfortunately I still can't create the base image... It keeps telling
> me that it is not responding to ssh (It also looks like Jay
> Diener-Brazelle had this problem that he posted at the end of Feb 2011
> but I don't see a solution reply to his either)
>
> management node:
>
> [root@vcl vcl]# /usr/local/vcl/bin/vcld -setup
> VCL Management Node Setup
> ----------------------------------------------------------------------------
> Select a module to configure:
> 1. VCL Base Module
> 2. VCL Image State Module
> 3. Windows OS Module
>
> [vcld]
> Make a selection (1-3, 'c' to cancel): 2
> ----------------------------------------------------------------------------
> Choose an operation:
> 1. Capture Base Image
>
> [vcld/Image]
> Make a selection (1, 'c' to cancel): 1
>
> Enter the VCL login name or ID of the user who will own the image:
> [admin]:
>
> User who will own the image: admin (ID: 1)
>
> Enter the hostname or IP address of the computer to be captured: ('c'
> to cancel): vm2
>
> Computer to be captured: vm2 (ID: 3)
> Provisioning module: provisioning_vmware
> Install type: vmware
>
> Select the OS to be captured (install type: vmware):
> 1. Generic Linux (VMware)
> 2. Windows 2003 Server (VMware)
> 3. Windows 7 (VMware)
> 4. Windows Server 2008 (VMware)
> 5. Windows Vista (VMware)
> 6. Windows XP (VMware)
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-6, 'c' to cancel): 6
>
> Selected OS: Windows XP (VMware)
>
> Image architecture:
> 1. x86
> 2. x86_64
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 1
>
> Image architecture: x86
>
> Use Sysprep:
> 1. Yes
> 2. No
>
> [vcld/Image/Capture Base Image]
> Make a selection (1-2, 'c' to cancel): 2
>
> Use Sysprep: No
>
> Enter the name of the image to be captured: ('c' to cancel): win_xp
>
> Added new image to database: 'win_xp'
> image.name <http://image.name>: vmwarewinxp-win_xp14-v0
> image.id <http://image.id>: 14
> imagerevision.id <http://imagerevision.id>: 14
> imagemeta.id <http://imagemeta.id>: 12
> resource.id <http://resource.id>: 24
>
> ----------------------------------------------------------------------------
> Inserted imaging request to the database:
> request ID: 10
> reservation ID: 10
>
> This process will now display the contents of the vcld.log file if the
> vcld
> daemon is running. If you do not see many lines of additional output,
> exit this
> process, start the vcld daemon, and monitor the image capture process
> by running
> the command:
> tail -f /var/log/vcld.log | grep '10:10'
>
> ----------------------------------------------------------------------------
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8343)|inserted new
> reload request into request table, request id=10
> 2011-03-08 14:40:01|20476|utils.pm:insert_request(8374)|inserted new
> reload request into reservation table, reservation id=10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8923)|computerloadlog
> 'begin' entry does NOT exist for reservation 10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:run_command(8995)|executed
> command: pgrep -fl 'vcld [0-9]+:10 ', pid: 20517, exit status: 1, output:
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:is_management_node_process_running(9177)|process
> is NOT running, identifier: 'vcld [0-9]+:10 '
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8944)|reservation
> is NOT currently being processed
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(278)|reservation 10 is
> NOT already being processed
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:get_request_info(4510)|standalone
> affiliation found: Local
> 2011-03-08 14:40:05|13551|10:10|image|vcld:main(283)|retrieved request
> information from database
> 2011-03-08
> 14:40:05|13551|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: pending, laststate to: image
> 2011-03-08
> 14:40:05|13551|10:10|image|utils.pm:insertloadlog(3875)|inserted
> computer=3, begin, beginning to process, state is image
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(515)|loaded
> VCL::image module
> 2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(539)|current
> number of forked kids: 1
> 2011-03-08 14:40:05|20532|10:10|image|vcld:make_new_child(555)|vcld
> environment variable set to 0 for this process
> 2011-03-08 14:40:05|20532|10:10|image|Module.pm:new(161)|VCL::image
> object created for state image, address: 9a54b24
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:initialize(86)|obtained
> a database handle for this state process, stored as $ENV{dbh}
> 2011-03-08 14:40:05|20532|10:10|image|State.pm:check_image_os(772)|no
> corrections need to be made to image OS: vmwarewinxp
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7082)|reservation
> count: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7101)|PARENTIMAGE:
> 1
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7102)|SUBIMAGE: 0
> 2011-03-08
> 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7120)|renamed
> process to 'vcld 10:10 image vm2>localvmhost vmwarewinxp-win_xp14-v0
> admin'
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
> true: parent reservation ID for this request: 10
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(267)|VCL::Module::OS::Windows::Version_5::XP
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Windows::Version_5::XP
> object created for image vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_os_object(273)|VCL::Module::OS::Windows::Version_5::XP
> OS object created for vmwarewinxp-win_xp14-v0, address: 9e7bbdc
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(330)|VCL::Module::OS::Linux::ManagementNode
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::ManagementNode
> object created for image <not set>, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{hostname}
> |20532|10:10|image| computer_hostname = vcl.tylerhardesty.info
> <http://vcl.tylerhardesty.info>
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{NODENAME}
> |20532|10:10|image| computer_node_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{SHORTNAME}
> |20532|10:10|image| computer_short_name = vcl
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{0}{computer}{IPaddress}
> |20532|10:10|image| computer_ip_address = 144.38.205.249
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(335)|VCL::Module::OS::Linux::ManagementNode
> OS object created, address: 9e7c074
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:create_provisioning_object(389)|VCL::Module::Provisioning::VMware::VMware
> module loaded
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VMware
> object created for computer vm2, address: 9f75548
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:initialize(230)|initializing
> VCL::Module::Provisioning::VMware::VMware object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer
> ID argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:initialize(249)|attempting to
> create OS object for the image currently loaded on the VM host:
> localvmhost
> |20532|10:10|image| image name: noimage
> |20532|10:10|image| OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer
> ID argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1229)|attempting
> to load VM host OS module: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1235)|VM
> host OS module loaded: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::UnixLab
> object created for image noimage, address: a18b8a8
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1241)|VM
> host OS object created: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:05|20532|10:10|image|OS.pm:is_ssh_responding(401)|localvmhost is
> responding to SSH, port 22: open, port 24: closed
> 2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(254)|OS on
> VM host localvmhost will be controlled using a
> VCL::Module::OS::Linux::UnixLab OS object
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer
> ID argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module:
> VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded VMware
> control module: VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::vSphere_SDK
> object created for computer vm2, address: a18e41c
> 2011-03-08
> 14:40:05|20532|10:10|image|vSphere_SDK.pm:initialize(1826)|vSphere SDK
> for Perl does not appear to be installed on this managment node,
> unable to load VMware vSphere SDK Perl modules
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1310)|API
> object could not be created:
> VCL::Module::Provisioning::VMware::vSphere_SDK
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer
> ID argument was specified, retrieving data for computer ID: 1
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
> argument was specified: 4, DataStructure object will contain image
> information for the production imagerevision of this image
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved
> data for imagerevision ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved
> data for image ID: 4
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
> DataStructure object for VM host: localvmhost
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
> to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded VMware
> control module: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VIM_SSH
> object created for computer vm2, address: a1a0118
> 2011-03-08 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(138)|VIM
> executable available on VM host: vmware-vim-cmd
> 2011-03-08
> 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH
> object initialized
> 2011-03-08
> 14:40:06|20532|10:10|image|VMware.pm:get_vmhost_api_object(1317)|created
> API object: VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(278)|VMware
> on VM host localvmhost will be controlled using vim-cmd via SSH
> 2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(293)|VMware
> OS and API objects created for VM host localvmhost:
> |20532|10:10|image| VM host OS object type:
> VCL::Module::OS::Linux::UnixLab
> |20532|10:10|image| VMware API object type:
> VCL::Module::Provisioning::VMware::VIM_SSH
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmhost_product_name(5374)|VMware
> product being used on VM host localvmhost: 'VMware Server 2.0.2
> build-203138'
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:initialize(307)|product: VMware
> Server 2.0.2 build-203138, OS object: VCL::Module::OS::Linux::UnixLab
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:get_vmx_base_directory_path(2736)|determined
> vmx base directory path: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(324)|not
> checking if vmdk base directory exists because it is the same as the
> vmx base directory: /var/lib/vmware/Virtual Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|Module.pm:create_provisioning_object(395)|VCL::Module::Provisioning::VMware::VMware
> provisioner object created for vm2, address: 9f75548
> 2011-03-08 14:40:08|20532|10:10|image|State.pm:initialize(136)|returning 1
> 2011-03-08
> 14:40:08|20532|10:10|image|vcld:make_new_child(565)|VCL::image object
> created and initialized
> 2011-03-08 14:40:08|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, VCL IMAGE Creation Started:
> vmwarewinxp-win_xp14-v0
> 2011-03-08 14:40:08|20532|10:10|image|Linux.pm:file_exists(1368)|file
> or directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3906)|image does
> not exist in the non-persistent directory on the VM host
> 2011-03-08
> 14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
> Machines' exists on localvmhost, files: 0, directories: 1, links: 0
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:is_repository_mounted_on_vmhost(5454)|image
> repository is mounted on VM host localvmhost: /var/lib/vmware/Virtual
> Machines
> 2011-03-08
> 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3917)|checking
> if vmdk file exists in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|Linux.pm:file_exists(1368)|file
> or directory does not exist on localvmhost: '/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
> 2011-03-08
> 14:40:09|20532|10:10|image|VMware.pm:does_image_exist(3923)|vmdk file
> does not exist in image repository mounted on VM host:
> localvmhost:/var/lib/vmware/Virtual
> Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(148)|image
> vmwarewinxp-win_xp14-v0 does not exist in the repository
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{10}{image}{lastupdate}
> |20532|10:10|image| image_lastupdate = 2011-03-08 14:40:09
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
> structure updated:
> $self->request_data->{reservation}{10}{imagerevision}{datecreated}
> |20532|10:10|image| imagerevision_date_created = 2011-03-08 14:40:09
> 2011-03-08 14:40:09|20532|10:10|image|image.pm:process(164)|calling
> provisioning module's capture() subroutine
> 2011-03-08 14:40:09|20532|10:10|image|OS.pm:is_ssh_responding(386)|vm2
> is NOT responding to SSH, ports 22 or 24 are both closed
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|VMware.pm:capture(462)|unable to capture
> image, VM vm2 is not responding to SSH
> |20532|10:10|image| ( 0) VMware.pm, capture (line: 462)
> |20532|10:10|image| (-1) image.pm <http://image.pm>, process (line: 165)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> |20532|10:10|image| ---- WARNING ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:process(169)|vmwarewinxp-win_xp14-v0
> image failed to be captured by provisioning module
> |20532|10:10|image| ( 0) image.pm <http://image.pm>, process (line: 169)
> |20532|10:10|image| (-1) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-2) vcld, main (line: 346)
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> to retrieve private IP address for computer: vm2
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> contents of /etc/hosts on this management node, contains 7 lines
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> IP address from /etc/hosts file: 192.168.1.1
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:is_inblockrequest(6147)|zero rows
> were returned from database select
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> owner id: 1
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database requested
> (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:127.0.0.1)
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database handle
> stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> to retrieve and store data for user: user.id <http://user.id> = '1'
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database requested
> (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:127.0.0.1)
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database handle
> stored in $ENV{dbh}
> 2011-03-08
> 14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1416)|data
> has been retrieved for user: admin (id: 1)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, PROBLEM -- 10:10|image|image.pm
> <http://image.pm>|vm2>localvmhost|vmwarewinxp-win_xp14-v0|admin
> |20532|10:10|image| ---- CRITICAL ----
> |20532|10:10|image| 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(393)|vmwarewinxp-win_xp14-v0
> image creation failed
> |20532|10:10|image| ( 0) image.pm <http://image.pm>,
> reservation_failed (line: 393)
> |20532|10:10|image| (-1) image.pm <http://image.pm>, process (line: 170)
> |20532|10:10|image| (-2) vcld, make_new_child (line: 568)
> |20532|10:10|image| (-3) vcld, main (line: 346)
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: root@localhost, VCL -- NOTICE DELAY Image Creation win_xp
> 2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, VCL -- NOTICE FAILED Image
> Creation win_xp
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:update_request_state(1991)|request
> 10 state updated to: maintenance, laststate to: image
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(445)|request
> state set to maintenance, laststate to image
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:update_computer_state(2033)|computer
> 3 state updated to: maintenance
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(453)|vm2 state
> set to maintenance
> 2011-03-08
> 14:40:09|20532|10:10|image|image.pm:reservation_failed(460)|exiting
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(839)|VCL::image
> destructor called, address: 9a54b24
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6726)|removing
> computerloadlog entries matching loadstate = begin
> 2011-03-08
> 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6773)|deleted
> rows from computerloadlog for reservation id=10
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(858)|number of
> database handles state process created: 3
> 2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(886)|VCL::image
> process duration: 4 seconds
> 2011-03-08 14:40:09|13551|vcld:REAPER(718)|VCL process exited for
> reservation 10, PID: 20532, signal: CHLD
> 2011-03-08 14:42:10|13551|vcld:main(167)|lastcheckin time updated for
> management node 1: 2011-03-08 14:42:10
>
>
> On Tue, Mar 8, 2011 at 2:00 PM, James O'Dell <jodell@fullerton.edu
> <ma...@fullerton.edu>> wrote:
>
> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA1
>
> Hi,
>
> I've gone through and compared your ssh debug info with mine.
>
> It looks like your '/etc/ssh/ssh_config' is different than
> mine. I added the following to the end of the file when I built
> vcl:
>
> > IdentityFile /opt/vcl/etc/vcl/vcl.key
> > StrictHostKeyChecking no
>
>
> I think this reflects the problem. You get:
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /root/.ssh/identity ((nil))
> > debug2: key: /root/.ssh/id_rsa ((nil))
> > debug2: key: /root/.ssh/id_dsa ((nil))
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
>
> I get
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /opt/vcl/etc/vcl/vcl.key (0x2ad12fbccc30)
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
>
>
> Also, where you get
> > Warning: Permanently added '144.38.205.252' (RSA) to the list of
> known
>
> I get
> > debug1: Host 'vclvm010511' is known and matches the RSA host key.
> > debug1: Found key in /root/.ssh/known_hosts:856
>
>
> Here's where it really diverges. You have
> > debug1: Next authentication method: publickey
> > debug1: Trying private key: /root/.ssh/identity
> > debug3: no such identity: /root/.ssh/identity
> > debug1: Trying private key: /root/.ssh/id_rsa
> > debug3: no such identity: /root/.ssh/id_rsa
> > debug1: Trying private key: /root/.ssh/id_dsa
> > debug3: no such identity: /root/.ssh/id_dsa
> > debug2: we did not send a packet, disable method
> > debug3: authmethod_lookup keyboard-interactive
> > debug3: remaining preferred: password
> > debug3: authmethod_is_enabled keyboard-interactive
> > debug1: Next authentication method: keyboard-interactive
> > debug2: userauth_kbdint
> > debug2: we sent a keyboard-interactive packet, wait for reply
> > debug1: Authentications that can continue:
> publickey,keyboard-interactive
> > debug3: userauth_kbdint: disable: no info_req_seen
> > debug2: we did not send a packet, disable method
> > debug1: No more authentication methods to try.
>
> I have
> > debug1: Next authentication method: publickey
> > debug1: Offering public key: /opt/vcl/etc/vcl/vcl.key
> > debug3: send_pubkey_test
> > debug2: we sent a publickey packet, wait for reply
> > debug1: Server accepts key: pkalg ssh-rsa blen 149
>
>
>
> Hope this helps,
>
> __Jim
>
Re: ssh on vmguest
Posted by Tyler Hardesty <th...@gmail.com>.
Hey thanks!
adding those lines helped solve my permission denied problem but
unfortunately I still can't create the base image... It keeps telling me
that it is not responding to ssh (It also looks like Jay Diener-Brazelle had
this problem that he posted at the end of Feb 2011 but I don't see a
solution reply to his either)
management node:
[root@vcl vcl]# /usr/local/vcl/bin/vcld -setup
VCL Management Node Setup
----------------------------------------------------------------------------
Select a module to configure:
1. VCL Base Module
2. VCL Image State Module
3. Windows OS Module
[vcld]
Make a selection (1-3, 'c' to cancel): 2
----------------------------------------------------------------------------
Choose an operation:
1. Capture Base Image
[vcld/Image]
Make a selection (1, 'c' to cancel): 1
Enter the VCL login name or ID of the user who will own the image: [admin]:
User who will own the image: admin (ID: 1)
Enter the hostname or IP address of the computer to be captured: ('c' to
cancel): vm2
Computer to be captured: vm2 (ID: 3)
Provisioning module: provisioning_vmware
Install type: vmware
Select the OS to be captured (install type: vmware):
1. Generic Linux (VMware)
2. Windows 2003 Server (VMware)
3. Windows 7 (VMware)
4. Windows Server 2008 (VMware)
5. Windows Vista (VMware)
6. Windows XP (VMware)
[vcld/Image/Capture Base Image]
Make a selection (1-6, 'c' to cancel): 6
Selected OS: Windows XP (VMware)
Image architecture:
1. x86
2. x86_64
[vcld/Image/Capture Base Image]
Make a selection (1-2, 'c' to cancel): 1
Image architecture: x86
Use Sysprep:
1. Yes
2. No
[vcld/Image/Capture Base Image]
Make a selection (1-2, 'c' to cancel): 2
Use Sysprep: No
Enter the name of the image to be captured: ('c' to cancel): win_xp
Added new image to database: 'win_xp'
image.name: vmwarewinxp-win_xp14-v0
image.id: 14
imagerevision.id: 14
imagemeta.id: 12
resource.id: 24
----------------------------------------------------------------------------
Inserted imaging request to the database:
request ID: 10
reservation ID: 10
This process will now display the contents of the vcld.log file if the vcld
daemon is running. If you do not see many lines of additional output, exit
this
process, start the vcld daemon, and monitor the image capture process by
running
the command:
tail -f /var/log/vcld.log | grep '10:10'
----------------------------------------------------------------------------
2011-03-08 14:40:01|20476|utils.pm:insert_request(8343)|inserted new reload
request into request table, request id=10
2011-03-08 14:40:01|20476|utils.pm:insert_request(8374)|inserted new reload
request into reservation table, reservation id=10
2011-03-08 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8923)|computerloadlog
'begin' entry does NOT exist for reservation 10
2011-03-08 14:40:05|13551|10:10|image|utils.pm:run_command(8995)|executed
command: pgrep -fl 'vcld [0-9]+:10 ', pid: 20517, exit status: 1, output:
2011-03-08 14:40:05|13551|10:10|image|utils.pm:is_management_node_process_running(9177)|process
is NOT running, identifier: 'vcld [0-9]+:10 '
2011-03-08 14:40:05|13551|10:10|image|utils.pm:reservation_being_processed(8944)|reservation
is NOT currently being processed
2011-03-08 14:40:05|13551|10:10|image|vcld:main(278)|reservation 10 is NOT
already being processed
2011-03-08 14:40:05|13551|10:10|image|utils.pm:get_request_info(4510)|standalone
affiliation found: Local
2011-03-08 14:40:05|13551|10:10|image|vcld:main(283)|retrieved request
information from database
2011-03-08
14:40:05|13551|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
true: parent reservation ID for this request: 10
2011-03-08 14:40:05|13551|10:10|image|utils.pm:update_request_state(1991)|request
10 state updated to: pending, laststate to: image
2011-03-08 14:40:05|13551|10:10|image|utils.pm:insertloadlog(3875)|inserted
computer=3, begin, beginning to process, state is image
2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(515)|loaded
VCL::image module
2011-03-08 14:40:05|13551|10:10|image|vcld:make_new_child(539)|current
number of forked kids: 1
2011-03-08 14:40:05|20532|10:10|image|vcld:make_new_child(555)|vcld
environment variable set to 0 for this process
2011-03-08 14:40:05|20532|10:10|image|Module.pm:new(161)|VCL::image object
created for state image, address: 9a54b24
2011-03-08 14:40:05|20532|10:10|image|State.pm:initialize(86)|obtained a
database handle for this state process, stored as $ENV{dbh}
2011-03-08 14:40:05|20532|10:10|image|State.pm:check_image_os(772)|no
corrections need to be made to image OS: vmwarewinxp
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
true: parent reservation ID for this request: 10
2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7082)|reservation
count: 1
2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7101)|PARENTIMAGE:
1
2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7102)|SUBIMAGE:
0
2011-03-08 14:40:05|20532|10:10|image|utils.pm:rename_vcld_process(7120)|renamed
process to 'vcld 10:10 image vm2>localvmhost vmwarewinxp-win_xp14-v0 admin'
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
true: parent reservation ID for this request: 10
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:is_parent_reservation(992)|returning
true: parent reservation ID for this request: 10
2011-03-08
14:40:05|20532|10:10|image|Module.pm:create_os_object(267)|VCL::Module::OS::Windows::Version_5::XP
module loaded
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Windows::Version_5::XP
object created for image vmwarewinxp-win_xp14-v0, address: 9e7bbdc
2011-03-08
14:40:05|20532|10:10|image|Module.pm:create_os_object(273)|VCL::Module::OS::Windows::Version_5::XP
OS object created for vmwarewinxp-win_xp14-v0, address: 9e7bbdc
2011-03-08
14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(330)|VCL::Module::OS::Linux::ManagementNode
module loaded
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::ManagementNode
object created for image <not set>, address: 9e7c074
2011-03-08 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated: $self->request_data->{reservation}{0}{computer}{hostname}
|20532|10:10|image| computer_hostname = vcl.tylerhardesty.info
2011-03-08 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated: $self->request_data->{reservation}{0}{computer}{NODENAME}
|20532|10:10|image| computer_node_name = vcl
2011-03-08 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated:
$self->request_data->{reservation}{0}{computer}{SHORTNAME}
|20532|10:10|image| computer_short_name = vcl
2011-03-08 14:40:05|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated:
$self->request_data->{reservation}{0}{computer}{IPaddress}
|20532|10:10|image| computer_ip_address = 144.38.205.249
2011-03-08
14:40:05|20532|10:10|image|Module.pm:create_mn_os_object(335)|VCL::Module::OS::Linux::ManagementNode
OS object created, address: 9e7c074
2011-03-08
14:40:05|20532|10:10|image|Module.pm:create_provisioning_object(389)|VCL::Module::Provisioning::VMware::VMware
module loaded
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VMware
object created for computer vm2, address: 9f75548
2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(230)|initializing
VCL::Module::Provisioning::VMware::VMware object
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
argument was specified, retrieving data for computer ID: 1
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
for imagerevision ID: 4
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
for image ID: 4
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
DataStructure object for VM host: localvmhost
2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(249)|attempting
to create OS object for the image currently loaded on the VM host:
localvmhost
|20532|10:10|image| image name: noimage
|20532|10:10|image| OS module: VCL::Module::OS::Linux::UnixLab
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
argument was specified, retrieving data for computer ID: 1
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
for imagerevision ID: 4
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
for image ID: 4
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
DataStructure object for VM host: localvmhost
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1229)|attempting
to load VM host OS module: VCL::Module::OS::Linux::UnixLab
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1235)|VM host OS
module loaded: VCL::Module::OS::Linux::UnixLab
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(165)|VCL::Module::OS::Linux::UnixLab
object created for image noimage, address: a18b8a8
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_os_object(1241)|VM host OS
object created: VCL::Module::OS::Linux::UnixLab
2011-03-08
14:40:05|20532|10:10|image|OS.pm:is_ssh_responding(401)|localvmhost is
responding to SSH, port 22: open, port 24: closed
2011-03-08 14:40:05|20532|10:10|image|VMware.pm:initialize(254)|OS on VM
host localvmhost will be controlled using a VCL::Module::OS::Linux::UnixLab
OS object
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
argument was specified, retrieving data for computer ID: 1
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
for imagerevision ID: 4
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
for image ID: 4
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
DataStructure object for VM host: localvmhost
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
to load VMware control module:
VCL::Module::Provisioning::VMware::vSphere_SDK
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
VMware control module: VCL::Module::Provisioning::VMware::vSphere_SDK
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::vSphere_SDK
object created for computer vm2, address: a18e41c
2011-03-08
14:40:05|20532|10:10|image|vSphere_SDK.pm:initialize(1826)|vSphere SDK for
Perl does not appear to be installed on this managment node, unable to load
VMware vSphere SDK Perl modules
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1310)|API object
could not be created: VCL::Module::Provisioning::VMware::vSphere_SDK
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(614)|computer ID
argument was specified, retrieving data for computer ID: 1
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(644)|image ID
argument was specified: 4, DataStructure object will contain image
information for the production imagerevision of this image
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(661)|retrieved data
for imagerevision ID: 4
2011-03-08
14:40:05|20532|10:10|image|DataStructure.pm:_initialize(677)|retrieved data
for image ID: 4
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_datastructure(1187)|created
DataStructure object for VM host: localvmhost
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1290)|attempting
to load VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2011-03-08
14:40:05|20532|10:10|image|VMware.pm:get_vmhost_api_object(1296)|loaded
VMware control module: VCL::Module::Provisioning::VMware::VIM_SSH
2011-03-08
14:40:05|20532|10:10|image|Module.pm:new(169)|VCL::Module::Provisioning::VMware::VIM_SSH
object created for computer vm2, address: a1a0118
2011-03-08 14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(138)|VIM
executable available on VM host: vmware-vim-cmd
2011-03-08
14:40:06|20532|10:10|image|VIM_SSH.pm:initialize(140)|VCL::Module::Provisioning::VMware::VIM_SSH
object initialized
2011-03-08
14:40:06|20532|10:10|image|VMware.pm:get_vmhost_api_object(1317)|created API
object: VCL::Module::Provisioning::VMware::VIM_SSH
2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(278)|VMware on VM
host localvmhost will be controlled using vim-cmd via SSH
2011-03-08 14:40:06|20532|10:10|image|VMware.pm:initialize(293)|VMware OS
and API objects created for VM host localvmhost:
|20532|10:10|image| VM host OS object type: VCL::Module::OS::Linux::UnixLab
|20532|10:10|image| VMware API object type:
VCL::Module::Provisioning::VMware::VIM_SSH
2011-03-08
14:40:08|20532|10:10|image|VMware.pm:get_vmhost_product_name(5374)|VMware
product being used on VM host localvmhost: 'VMware Server 2.0.2
build-203138'
2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(307)|product:
VMware Server 2.0.2 build-203138, OS object: VCL::Module::OS::Linux::UnixLab
2011-03-08
14:40:08|20532|10:10|image|VMware.pm:get_vmx_base_directory_path(2736)|determined
vmx base directory path: /var/lib/vmware/Virtual Machines
2011-03-08
14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
Machines' exists on localvmhost, files: 0, directories: 1, links: 0
2011-03-08 14:40:08|20532|10:10|image|VMware.pm:initialize(324)|not checking
if vmdk base directory exists because it is the same as the vmx base
directory: /var/lib/vmware/Virtual Machines
2011-03-08
14:40:08|20532|10:10|image|Module.pm:create_provisioning_object(395)|VCL::Module::Provisioning::VMware::VMware
provisioner object created for vm2, address: 9f75548
2011-03-08 14:40:08|20532|10:10|image|State.pm:initialize(136)|returning 1
2011-03-08 14:40:08|20532|10:10|image|vcld:make_new_child(565)|VCL::image
object created and initialized
2011-03-08 14:40:08|20532|10:10|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, VCL IMAGE Creation Started:
vmwarewinxp-win_xp14-v0
2011-03-08 14:40:08|20532|10:10|image|Linux.pm:file_exists(1368)|file or
directory does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
2011-03-08 14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3906)|image
does not exist in the non-persistent directory on the VM host
2011-03-08
14:40:08|20532|10:10|image|Linux.pm:file_exists(1382)|'/var/lib/vmware/Virtual
Machines' exists on localvmhost, files: 0, directories: 1, links: 0
2011-03-08
14:40:08|20532|10:10|image|VMware.pm:is_repository_mounted_on_vmhost(5454)|image
repository is mounted on VM host localvmhost: /var/lib/vmware/Virtual
Machines
2011-03-08
14:40:08|20532|10:10|image|VMware.pm:does_image_exist(3917)|checking if vmdk
file exists in image repository mounted on VM host:
localvmhost:/var/lib/vmware/Virtual
Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
2011-03-08 14:40:09|20532|10:10|image|Linux.pm:file_exists(1368)|file or
directory does not exist on localvmhost: '/var/lib/vmware/Virtual
Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk'
2011-03-08 14:40:09|20532|10:10|image|VMware.pm:does_image_exist(3923)|vmdk
file does not exist in image repository mounted on VM host:
localvmhost:/var/lib/vmware/Virtual
Machines/vmwarewinxp-win_xp14-v0/vmwarewinxp-win_xp14-v0.vmdk
2011-03-08 14:40:09|20532|10:10|image|image.pm:process(148)|image
vmwarewinxp-win_xp14-v0 does not exist in the repository
2011-03-08 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated: $self->request_data->{reservation}{10}{image}{lastupdate}
|20532|10:10|image| image_lastupdate = 2011-03-08 14:40:09
2011-03-08 14:40:09|20532|10:10|image|DataStructure.pm:_automethod(835)|data
structure updated:
$self->request_data->{reservation}{10}{imagerevision}{datecreated}
|20532|10:10|image| imagerevision_date_created = 2011-03-08 14:40:09
2011-03-08 14:40:09|20532|10:10|image|image.pm:process(164)|calling
provisioning module's capture() subroutine
2011-03-08 14:40:09|20532|10:10|image|OS.pm:is_ssh_responding(386)|vm2 is
NOT responding to SSH, ports 22 or 24 are both closed
|20532|10:10|image| ---- WARNING ----
|20532|10:10|image| 2011-03-08
14:40:09|20532|10:10|image|VMware.pm:capture(462)|unable to capture image,
VM vm2 is not responding to SSH
|20532|10:10|image| ( 0) VMware.pm, capture (line: 462)
|20532|10:10|image| (-1) image.pm, process (line: 165)
|20532|10:10|image| (-2) vcld, make_new_child (line: 568)
|20532|10:10|image| (-3) vcld, main (line: 346)
|20532|10:10|image| ---- WARNING ----
|20532|10:10|image| 2011-03-08
14:40:09|20532|10:10|image|image.pm:process(169)|vmwarewinxp-win_xp14-v0
image failed to be captured by provisioning module
|20532|10:10|image| ( 0) image.pm, process (line: 169)
|20532|10:10|image| (-1) vcld, make_new_child (line: 568)
|20532|10:10|image| (-2) vcld, main (line: 346)
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
to retrieve private IP address for computer: vm2
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
contents of /etc/hosts on this management node, contains 7 lines
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
IP address from /etc/hosts file: 192.168.1.1
2011-03-08 14:40:09|20532|10:10|image|utils.pm:is_inblockrequest(6147)|zero
rows were returned from database select
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:get_image_affiliation_name(2029)|image
owner id: 1
2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
requested (information_schema) does not match handle stored in $ENV{dbh}
(vcl:127.0.0.1)
2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1353)|attempting
to retrieve and store data for user: user.id = '1'
2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2709)|database
requested (vcl) does not match handle stored in $ENV{dbh}
(information_schema:127.0.0.1)
2011-03-08 14:40:09|20532|10:10|image|utils.pm:getnewdbh(2760)|database
handle stored in $ENV{dbh}
2011-03-08
14:40:09|20532|10:10|image|DataStructure.pm:retrieve_user_data(1416)|data
has been retrieved for user: admin (id: 1)
2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, PROBLEM -- 10:10|image|image.pm
|vm2>localvmhost|vmwarewinxp-win_xp14-v0|admin
|20532|10:10|image| ---- CRITICAL ----
|20532|10:10|image| 2011-03-08
14:40:09|20532|10:10|image|image.pm:reservation_failed(393)|vmwarewinxp-win_xp14-v0
image creation failed
|20532|10:10|image| ( 0) image.pm, reservation_failed (line: 393)
|20532|10:10|image| (-1) image.pm, process (line: 170)
|20532|10:10|image| (-2) vcld, make_new_child (line: 568)
|20532|10:10|image| (-3) vcld, main (line: 346)
2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: root@localhost, VCL -- NOTICE DELAY Image Creation win_xp
2011-03-08 14:40:09|20532|10:10|image|utils.pm:mail(1268)|SUCCESS -- Sending
mail To: thardesty@tylerhardesty.info, VCL -- NOTICE FAILED Image Creation
win_xp
2011-03-08 14:40:09|20532|10:10|image|utils.pm:update_request_state(1991)|request
10 state updated to: maintenance, laststate to: image
2011-03-08 14:40:09|20532|10:10|image|image.pm:reservation_failed(445)|request
state set to maintenance, laststate to image
2011-03-08 14:40:09|20532|10:10|image|utils.pm:update_computer_state(2033)|computer
3 state updated to: maintenance
2011-03-08 14:40:09|20532|10:10|image|image.pm:reservation_failed(453)|vm2
state set to maintenance
2011-03-08 14:40:09|20532|10:10|image|image.pm:
reservation_failed(460)|exiting
2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(839)|VCL::image
destructor called, address: 9a54b24
2011-03-08 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6726)|removing
computerloadlog entries matching loadstate = begin
2011-03-08 14:40:09|20532|10:10|image|utils.pm:delete_computerloadlog_reservation(6773)|deleted
rows from computerloadlog for reservation id=10
2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(858)|number of
database handles state process created: 3
2011-03-08 14:40:09|20532|10:10|image|State.pm:DESTROY(886)|VCL::image
process duration: 4 seconds
2011-03-08 14:40:09|13551|vcld:REAPER(718)|VCL process exited for
reservation 10, PID: 20532, signal: CHLD
2011-03-08 14:42:10|13551|vcld:main(167)|lastcheckin time updated for
management node 1: 2011-03-08 14:42:10
On Tue, Mar 8, 2011 at 2:00 PM, James O'Dell <jo...@fullerton.edu> wrote:
> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA1
>
> Hi,
>
> I've gone through and compared your ssh debug info with mine.
>
> It looks like your '/etc/ssh/ssh_config' is different than
> mine. I added the following to the end of the file when I built
> vcl:
>
> > IdentityFile /opt/vcl/etc/vcl/vcl.key
> > StrictHostKeyChecking no
>
>
> I think this reflects the problem. You get:
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /root/.ssh/identity ((nil))
> > debug2: key: /root/.ssh/id_rsa ((nil))
> > debug2: key: /root/.ssh/id_dsa ((nil))
> > debug1: Authentications that can continue: publickey,keyboard-interactive
>
> I get
> > debug1: SSH2_MSG_SERVICE_ACCEPT received
> > debug2: key: /opt/vcl/etc/vcl/vcl.key (0x2ad12fbccc30)
> > debug1: Authentications that can continue: publickey,keyboard-interactive
>
>
> Also, where you get
> > Warning: Permanently added '144.38.205.252' (RSA) to the list of known
>
> I get
> > debug1: Host 'vclvm010511' is known and matches the RSA host key.
> > debug1: Found key in /root/.ssh/known_hosts:856
>
>
> Here's where it really diverges. You have
> > debug1: Next authentication method: publickey
> > debug1: Trying private key: /root/.ssh/identity
> > debug3: no such identity: /root/.ssh/identity
> > debug1: Trying private key: /root/.ssh/id_rsa
> > debug3: no such identity: /root/.ssh/id_rsa
> > debug1: Trying private key: /root/.ssh/id_dsa
> > debug3: no such identity: /root/.ssh/id_dsa
> > debug2: we did not send a packet, disable method
> > debug3: authmethod_lookup keyboard-interactive
> > debug3: remaining preferred: password
> > debug3: authmethod_is_enabled keyboard-interactive
> > debug1: Next authentication method: keyboard-interactive
> > debug2: userauth_kbdint
> > debug2: we sent a keyboard-interactive packet, wait for reply
> > debug1: Authentications that can continue: publickey,keyboard-interactive
> > debug3: userauth_kbdint: disable: no info_req_seen
> > debug2: we did not send a packet, disable method
> > debug1: No more authentication methods to try.
>
> I have
> > debug1: Next authentication method: publickey
> > debug1: Offering public key: /opt/vcl/etc/vcl/vcl.key
> > debug3: send_pubkey_test
> > debug2: we sent a publickey packet, wait for reply
> > debug1: Server accepts key: pkalg ssh-rsa blen 149
>
>
>
> Hope this helps,
>
> __Jim
>
>
Re: ssh on vmguest
Posted by James O'Dell <jo...@fullerton.edu>.
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
Hi,
I've gone through and compared your ssh debug info with mine.
It looks like your '/etc/ssh/ssh_config' is different than
mine. I added the following to the end of the file when I built
vcl:
> IdentityFile /opt/vcl/etc/vcl/vcl.key
> StrictHostKeyChecking no
I think this reflects the problem. You get:
> debug1: SSH2_MSG_SERVICE_ACCEPT received
> debug2: key: /root/.ssh/identity ((nil))
> debug2: key: /root/.ssh/id_rsa ((nil))
> debug2: key: /root/.ssh/id_dsa ((nil))
> debug1: Authentications that can continue: publickey,keyboard-interactive
I get
> debug1: SSH2_MSG_SERVICE_ACCEPT received
> debug2: key: /opt/vcl/etc/vcl/vcl.key (0x2ad12fbccc30)
> debug1: Authentications that can continue: publickey,keyboard-interactive
Also, where you get
> Warning: Permanently added '144.38.205.252' (RSA) to the list of known
I get
> debug1: Host 'vclvm010511' is known and matches the RSA host key.
> debug1: Found key in /root/.ssh/known_hosts:856
Here's where it really diverges. You have
> debug1: Next authentication method: publickey
> debug1: Trying private key: /root/.ssh/identity
> debug3: no such identity: /root/.ssh/identity
> debug1: Trying private key: /root/.ssh/id_rsa
> debug3: no such identity: /root/.ssh/id_rsa
> debug1: Trying private key: /root/.ssh/id_dsa
> debug3: no such identity: /root/.ssh/id_dsa
> debug2: we did not send a packet, disable method
> debug3: authmethod_lookup keyboard-interactive
> debug3: remaining preferred: password
> debug3: authmethod_is_enabled keyboard-interactive
> debug1: Next authentication method: keyboard-interactive
> debug2: userauth_kbdint
> debug2: we sent a keyboard-interactive packet, wait for reply
> debug1: Authentications that can continue: publickey,keyboard-interactive
> debug3: userauth_kbdint: disable: no info_req_seen
> debug2: we did not send a packet, disable method
> debug1: No more authentication methods to try.
I have
> debug1: Next authentication method: publickey
> debug1: Offering public key: /opt/vcl/etc/vcl/vcl.key
> debug3: send_pubkey_test
> debug2: we sent a publickey packet, wait for reply
> debug1: Server accepts key: pkalg ssh-rsa blen 149
Hope this helps,
__Jim
On 3/8/2011 12:22 PM, Tyler Hardesty wrote:
> This is what I get when I do ssh -vvv.... it says connection established
> towards the beginning but I can't make heads or tails of the rest of
> it... P.S. I started over with cygwin on the vm (deleted and
> reinstalled) but still get the same warning message that vm2 isn't
> responding to ssh when I do a vcld -setup.
>
> output:
> [root@vcl ~]# ssh -vvv 144.38.205.252
> OpenSSH_4.3p2, OpenSSL 0.9.8e-fips-rhel5 01 Jul 2008
> debug1: Reading configuration data /root/.ssh/config
> debug1: Reading configuration data /etc/ssh/ssh_config
> debug1: Applying options for *
> debug2: ssh_connect: needpriv 0
> debug1: Connecting to 144.38.205.252 [144.38.205.252] port 22.
> debug1: Connection established.
> debug1: permanently_set_uid: 0/0
> debug1: identity file /root/.ssh/identity type -1
> debug1: identity file /root/.ssh/id_rsa type -1
> debug1: identity file /root/.ssh/id_dsa type -1
> debug1: loaded 3 keys
> debug1: Remote protocol version 2.0, remote software version OpenSSH_5.1
> debug1: match: OpenSSH_5.1 pat OpenSSH*
> debug1: Enabling compatibility mode for protocol 2.0
> debug1: Local version string SSH-2.0-OpenSSH_4.3
> debug2: fd 3 setting O_NONBLOCK
> debug1: SSH2_MSG_KEXINIT sent
> debug1: SSH2_MSG_KEXINIT received
> debug2: kex_parse_kexinit:
> diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
> debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
> debug2: kex_parse_kexinit:
> aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
> <ma...@lysator.liu.se>,aes128-ctr,aes192-ctr,aes256-ctr
> debug2: kex_parse_kexinit:
> aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
> <ma...@lysator.liu.se>,aes128-ctr,aes192-ctr,aes256-ctr
> debug2: kex_parse_kexinit:
> hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com
> <ma...@openssh.com>,hmac-sha1-96,hmac-md5-96
> debug2: kex_parse_kexinit:
> hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com
> <ma...@openssh.com>,hmac-sha1-96,hmac-md5-96
> debug2: kex_parse_kexinit: none,zlib@openssh.com
> <ma...@openssh.com>,zlib
> debug2: kex_parse_kexinit: none,zlib@openssh.com
> <ma...@openssh.com>,zlib
> debug2: kex_parse_kexinit:
> debug2: kex_parse_kexinit:
> debug2: kex_parse_kexinit: first_kex_follows 0
> debug2: kex_parse_kexinit: reserved 0
> debug2: kex_parse_kexinit:
> diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
> debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
> debug2: kex_parse_kexinit:
> aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
> <ma...@lysator.liu.se>,aes128-ctr,aes192-ctr,aes256-ctr
> debug2: kex_parse_kexinit:
> aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
> <ma...@lysator.liu.se>,aes128-ctr,aes192-ctr,aes256-ctr
> debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com
> <ma...@openssh.com>,hmac-ripemd160,hmac-ripemd160@openssh.com
> <ma...@openssh.com>,hmac-sha1-96,hmac-md5-96
> debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com
> <ma...@openssh.com>,hmac-ripemd160,hmac-ripemd160@openssh.com
> <ma...@openssh.com>,hmac-sha1-96,hmac-md5-96
> debug2: kex_parse_kexinit: none
> debug2: kex_parse_kexinit: none
> debug2: kex_parse_kexinit:
> debug2: kex_parse_kexinit:
> debug2: kex_parse_kexinit: first_kex_follows 0
> debug2: kex_parse_kexinit: reserved 0
> debug2: mac_init: found hmac-md5
> debug1: kex: server->client aes128-cbc hmac-md5 none
> debug2: mac_init: found hmac-md5
> debug1: kex: client->server aes128-cbc hmac-md5 none
> debug1: SSH2_MSG_KEX_DH_GEX_REQUEST(1024<1024<8192) sent
> debug1: expecting SSH2_MSG_KEX_DH_GEX_GROUP
> debug2: dh_gen_key: priv key bits set: 124/256
> debug2: bits set: 497/1024
> debug1: SSH2_MSG_KEX_DH_GEX_INIT sent
> debug1: expecting SSH2_MSG_KEX_DH_GEX_REPLY
> debug3: check_host_in_hostfile: filename /dev/null
> debug3: check_host_in_hostfile: filename /etc/ssh/ssh_known_hosts
> Warning: Permanently added '144.38.205.252' (RSA) to the list of known
> hosts.
> debug2: bits set: 502/1024
> debug1: ssh_rsa_verify: signature correct
> debug2: kex_derive_keys
> debug2: set_newkeys: mode 1
> debug1: SSH2_MSG_NEWKEYS sent
> debug1: expecting SSH2_MSG_NEWKEYS
> debug2: set_newkeys: mode 0
> debug1: SSH2_MSG_NEWKEYS received
> debug1: SSH2_MSG_SERVICE_REQUEST sent
> debug2: service_accept: ssh-userauth
> debug1: SSH2_MSG_SERVICE_ACCEPT received
> debug2: key: /root/.ssh/identity ((nil))
> debug2: key: /root/.ssh/id_rsa ((nil))
> debug2: key: /root/.ssh/id_dsa ((nil))
> debug1: Authentications that can continue: publickey,keyboard-interactive
> debug3: start over, passed a different list publickey,keyboard-interactive
> debug3: preferred gssapi-with-mic,publickey,keyboard-interactive,password
> debug3: authmethod_lookup publickey
> debug3: remaining preferred: keyboard-interactive,password
> debug3: authmethod_is_enabled publickey
> debug1: Next authentication method: publickey
> debug1: Trying private key: /root/.ssh/identity
> debug3: no such identity: /root/.ssh/identity
> debug1: Trying private key: /root/.ssh/id_rsa
> debug3: no such identity: /root/.ssh/id_rsa
> debug1: Trying private key: /root/.ssh/id_dsa
> debug3: no such identity: /root/.ssh/id_dsa
> debug2: we did not send a packet, disable method
> debug3: authmethod_lookup keyboard-interactive
> debug3: remaining preferred: password
> debug3: authmethod_is_enabled keyboard-interactive
> debug1: Next authentication method: keyboard-interactive
> debug2: userauth_kbdint
> debug2: we sent a keyboard-interactive packet, wait for reply
> debug1: Authentications that can continue: publickey,keyboard-interactive
> debug3: userauth_kbdint: disable: no info_req_seen
> debug2: we did not send a packet, disable method
> debug1: No more authentication methods to try.
> Permission denied (publickey,keyboard-interactive).
>
>
> On Mon, Mar 7, 2011 at 5:55 PM, James O'Dell <jodell@fullerton.edu
> <ma...@fullerton.edu>> wrote:
>
> It really looks like your keys are incorrect.
>
> try "ssh -vvv XXX.XXX.XXX.XXX", ssh will output debugging messages,
> and you should be able to see why you're getting permission denied
>
> __Jim
>
> On 3/7/2011 4:48 PM, Tyler Hardesty wrote:
>> Sorry, additional info---
>> when I do:
>> [root@vcl ~]# ssh -o CheckHostIP=no -o StrictHostKeyChecking=no -o
>> BatchMode=no -i /etc/vcl/vcl.key xxx.xx.xxx.xxx
>
>> It ssh's into the machine just fine.
>
>> On Mon, Mar 7, 2011 at 5:41 PM, Tyler Hardesty
> <thardesty@gmail.com <ma...@gmail.com>
>> <mailto:thardesty@gmail.com <ma...@gmail.com>>> wrote:
>
>> I've been trying to search through the mail archive but haven't
>> found a solution to my problem... sorry if this was already
> covered
>> and I just didn't find it...
>> I'm trying to capture an image of a win xp vm and I get this error
>> in the log file:
>> |16224|3:3|image| ---- WARNING ----
>> |16224|3:3|image| 2011-03-07
>> 17:18:36|16224|3:3|image|VMware.pm:capture(462)|unable to capture
>> image, VM vm2 is not responding to SSH
>> |16224|3:3|image| ( 0) VMware.pm, capture (line: 462)
>> |16224|3:3|image| (-1) image.pm <http://image.pm>
> <http://image.pm>, process (line: 165)
>> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
>> |16224|3:3|image| (-3) vcld, main (line: 346)
>> |16224|3:3|image| ---- WARNING ----
>> |16224|3:3|image| 2011-03-07
>
> 17:18:36|16224|3:3|image|image.pm:process(169)|vmwarewinxp-windowsxp7-v0
>> image failed to be captured by provisioning module
>> |16224|3:3|image| ( 0) image.pm <http://image.pm>
> <http://image.pm>, process (line: 169)
>> |16224|3:3|image| (-1) vcld, make_new_child (line: 568)
>> |16224|3:3|image| (-2) vcld, main (line: 346)
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
>> to retrieve private IP address for computer: vm2
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
>> contents of /etc/hosts on this management node, contains 7 lines
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
>> IP address from /etc/hosts file: 192.168.1.1
>> 2011-03-07
>> 17:18:36|16224|3:3|image|utils.pm:is_inblockrequest(6147)|zero
> rows
>> were returned from database select
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:get_image_affiliation_name(2029)|image
>> owner id: 1
>> 2011-03-07
>> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
> requested
>> (information_schema) does not match handle stored in $ENV{dbh}
>> (vcl:127.0.0.1)
>> 2011-03-07
>> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
>> stored in $ENV{dbh}
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1353)|attempting
>> to retrieve and store data for user: user.id <http://user.id>
> <http://user.id> = '1'
>> 2011-03-07
>> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
> requested
>> (vcl) does not match handle stored in $ENV{dbh}
>> (information_schema:127.0.0.1)
>> 2011-03-07
>> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
>> stored in $ENV{dbh}
>> 2011-03-07
>
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1416)|data
>> has been retrieved for user: admin (id: 1)
>> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:mail(1268)|SUCCESS --
>> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>
>> <mailto:thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>>, PROBLEM --
> 3:3|image|image.pm <http://image.pm>
>> <http://image.pm>|vm2>localvmhost|vmwarewinxp-windowsxp7-v0|admin
>> |16224|3:3|image| ---- CRITICAL ----
>> |16224|3:3|image| 2011-03-07
>
> 17:18:36|16224|3:3|image|image.pm:reservation_failed(393)|vmwarewinxp-windowsxp7-v0
>> image creation failed
>> |16224|3:3|image| ( 0) image.pm <http://image.pm>
> <http://image.pm>,
>> reservation_failed (line: 393)
>> |16224|3:3|image| (-1) image.pm <http://image.pm>
> <http://image.pm>, process (line: 170)
>> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
>> |16224|3:3|image| (-3) vcld, main (line: 346)
>
>> I've run the cygwin on the win xp vm and it was successfull...
>> copying and pasting that command it shows at the end of the setup
>> allowed me to ssh into it without having to put in a passwd or
>> whatever so then I tried to ssh to it from my management node
> and I get:
>> [thardesty@vcl ~]$ ssh xxx.xx.xxx.xxx
>> Warning: Permanently added 'xxx.xx.xxx.xxx' (RSA) to the list of
>> known hosts.
>> Permission denied (publickey,keyboard-interactive).
>
>> I can't seem to find what I am doing wrong so any help would
> be great!!
>
>> -Tyler Hardesty
>
>
>
>
- --
Jim O'Dell
Network Analyst
California State University Fullerton
Email: jodell@fullerton.edu
Phone: (657) 278-2256
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (MingW32)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/
iEYEARECAAYFAk12mPMACgkQREVHAOnXPYRVpACgjkSEs7ANyrVvDcdlyA5kfnxJ
VpgAoNXXcNHzg0Bhdn6hzK5fyL0Qpv59
=PVpv
-----END PGP SIGNATURE-----
Re: ssh on vmguest
Posted by Tyler Hardesty <th...@gmail.com>.
This is what I get when I do ssh -vvv.... it says connection established
towards the beginning but I can't make heads or tails of the rest of it...
P.S. I started over with cygwin on the vm (deleted and reinstalled) but
still get the same warning message that vm2 isn't responding to ssh when I
do a vcld -setup.
output:
[root@vcl ~]# ssh -vvv 144.38.205.252
OpenSSH_4.3p2, OpenSSL 0.9.8e-fips-rhel5 01 Jul 2008
debug1: Reading configuration data /root/.ssh/config
debug1: Reading configuration data /etc/ssh/ssh_config
debug1: Applying options for *
debug2: ssh_connect: needpriv 0
debug1: Connecting to 144.38.205.252 [144.38.205.252] port 22.
debug1: Connection established.
debug1: permanently_set_uid: 0/0
debug1: identity file /root/.ssh/identity type -1
debug1: identity file /root/.ssh/id_rsa type -1
debug1: identity file /root/.ssh/id_dsa type -1
debug1: loaded 3 keys
debug1: Remote protocol version 2.0, remote software version OpenSSH_5.1
debug1: match: OpenSSH_5.1 pat OpenSSH*
debug1: Enabling compatibility mode for protocol 2.0
debug1: Local version string SSH-2.0-OpenSSH_4.3
debug2: fd 3 setting O_NONBLOCK
debug1: SSH2_MSG_KEXINIT sent
debug1: SSH2_MSG_KEXINIT received
debug2: kex_parse_kexinit:
diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,
rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,
rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,
hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,
hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib
debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: kex_parse_kexinit:
diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,
rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,
rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com
,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com
,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
debug2: kex_parse_kexinit: none
debug2: kex_parse_kexinit: none
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit:
debug2: kex_parse_kexinit: first_kex_follows 0
debug2: kex_parse_kexinit: reserved 0
debug2: mac_init: found hmac-md5
debug1: kex: server->client aes128-cbc hmac-md5 none
debug2: mac_init: found hmac-md5
debug1: kex: client->server aes128-cbc hmac-md5 none
debug1: SSH2_MSG_KEX_DH_GEX_REQUEST(1024<1024<8192) sent
debug1: expecting SSH2_MSG_KEX_DH_GEX_GROUP
debug2: dh_gen_key: priv key bits set: 124/256
debug2: bits set: 497/1024
debug1: SSH2_MSG_KEX_DH_GEX_INIT sent
debug1: expecting SSH2_MSG_KEX_DH_GEX_REPLY
debug3: check_host_in_hostfile: filename /dev/null
debug3: check_host_in_hostfile: filename /etc/ssh/ssh_known_hosts
Warning: Permanently added '144.38.205.252' (RSA) to the list of known
hosts.
debug2: bits set: 502/1024
debug1: ssh_rsa_verify: signature correct
debug2: kex_derive_keys
debug2: set_newkeys: mode 1
debug1: SSH2_MSG_NEWKEYS sent
debug1: expecting SSH2_MSG_NEWKEYS
debug2: set_newkeys: mode 0
debug1: SSH2_MSG_NEWKEYS received
debug1: SSH2_MSG_SERVICE_REQUEST sent
debug2: service_accept: ssh-userauth
debug1: SSH2_MSG_SERVICE_ACCEPT received
debug2: key: /root/.ssh/identity ((nil))
debug2: key: /root/.ssh/id_rsa ((nil))
debug2: key: /root/.ssh/id_dsa ((nil))
debug1: Authentications that can continue: publickey,keyboard-interactive
debug3: start over, passed a different list publickey,keyboard-interactive
debug3: preferred gssapi-with-mic,publickey,keyboard-interactive,password
debug3: authmethod_lookup publickey
debug3: remaining preferred: keyboard-interactive,password
debug3: authmethod_is_enabled publickey
debug1: Next authentication method: publickey
debug1: Trying private key: /root/.ssh/identity
debug3: no such identity: /root/.ssh/identity
debug1: Trying private key: /root/.ssh/id_rsa
debug3: no such identity: /root/.ssh/id_rsa
debug1: Trying private key: /root/.ssh/id_dsa
debug3: no such identity: /root/.ssh/id_dsa
debug2: we did not send a packet, disable method
debug3: authmethod_lookup keyboard-interactive
debug3: remaining preferred: password
debug3: authmethod_is_enabled keyboard-interactive
debug1: Next authentication method: keyboard-interactive
debug2: userauth_kbdint
debug2: we sent a keyboard-interactive packet, wait for reply
debug1: Authentications that can continue: publickey,keyboard-interactive
debug3: userauth_kbdint: disable: no info_req_seen
debug2: we did not send a packet, disable method
debug1: No more authentication methods to try.
Permission denied (publickey,keyboard-interactive).
On Mon, Mar 7, 2011 at 5:55 PM, James O'Dell <jo...@fullerton.edu> wrote:
> -----BEGIN PGP SIGNED MESSAGE-----
> Hash: SHA1
>
> It really looks like your keys are incorrect.
>
> try "ssh -vvv XXX.XXX.XXX.XXX", ssh will output debugging messages,
> and you should be able to see why you're getting permission denied
>
> __Jim
>
> On 3/7/2011 4:48 PM, Tyler Hardesty wrote:
> > Sorry, additional info---
> > when I do:
> > [root@vcl ~]# ssh -o CheckHostIP=no -o StrictHostKeyChecking=no -o
> > BatchMode=no -i /etc/vcl/vcl.key xxx.xx.xxx.xxx
> >
> > It ssh's into the machine just fine.
> >
> > On Mon, Mar 7, 2011 at 5:41 PM, Tyler Hardesty <thardesty@gmail.com
> > <ma...@gmail.com>> wrote:
> >
> > I've been trying to search through the mail archive but haven't
> > found a solution to my problem... sorry if this was already covered
> > and I just didn't find it...
> > I'm trying to capture an image of a win xp vm and I get this error
> > in the log file:
> > |16224|3:3|image| ---- WARNING ----
> > |16224|3:3|image| 2011-03-07
> > 17:18:36|16224|3:3|image|VMware.pm:capture(462)|unable to capture
> > image, VM vm2 is not responding to SSH
> > |16224|3:3|image| ( 0) VMware.pm, capture (line: 462)
> > |16224|3:3|image| (-1) image.pm <http://image.pm>, process (line:
> 165)
> > |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> > |16224|3:3|image| (-3) vcld, main (line: 346)
> > |16224|3:3|image| ---- WARNING ----
> > |16224|3:3|image| 2011-03-07
> > 17:18:36|16224|3:3|image|image.pm:
> process(169)|vmwarewinxp-windowsxp7-v0
> > image failed to be captured by provisioning module
> > |16224|3:3|image| ( 0) image.pm <http://image.pm>, process (line:
> 169)
> > |16224|3:3|image| (-1) vcld, make_new_child (line: 568)
> > |16224|3:3|image| (-2) vcld, main (line: 346)
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> > to retrieve private IP address for computer: vm2
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> > contents of /etc/hosts on this management node, contains 7 lines
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> > IP address from /etc/hosts file: 192.168.1.1
> > 2011-03-07
> > 17:18:36|16224|3:3|image|utils.pm:is_inblockrequest(6147)|zero rows
> > were returned from database select
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> > owner id: 1
> > 2011-03-07
> > 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database requested
> > (information_schema) does not match handle stored in $ENV{dbh}
> > (vcl:127.0.0.1)
> > 2011-03-07
> > 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
> > stored in $ENV{dbh}
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> > to retrieve and store data for user: user.id <http://user.id> = '1'
> > 2011-03-07
> > 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database requested
> > (vcl) does not match handle stored in $ENV{dbh}
> > (information_schema:127.0.0.1)
> > 2011-03-07
> > 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
> > stored in $ENV{dbh}
> > 2011-03-07
> >
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1416)|data
> > has been retrieved for user: admin (id: 1)
> > 2011-03-07 17:18:36|16224|3:3|image|utils.pm:mail(1268)|SUCCESS --
> > Sending mail To: thardesty@tylerhardesty.info
> > <ma...@tylerhardesty.info>, PROBLEM -- 3:3|image|image.pm
> > <http://image.pm>|vm2>localvmhost|vmwarewinxp-windowsxp7-v0|admin
> > |16224|3:3|image| ---- CRITICAL ----
> > |16224|3:3|image| 2011-03-07
> > 17:18:36|16224|3:3|image|image.pm:
> reservation_failed(393)|vmwarewinxp-windowsxp7-v0
> > image creation failed
> > |16224|3:3|image| ( 0) image.pm <http://image.pm>,
> > reservation_failed (line: 393)
> > |16224|3:3|image| (-1) image.pm <http://image.pm>, process (line:
> 170)
> > |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> > |16224|3:3|image| (-3) vcld, main (line: 346)
> >
> > I've run the cygwin on the win xp vm and it was successfull...
> > copying and pasting that command it shows at the end of the setup
> > allowed me to ssh into it without having to put in a passwd or
> > whatever so then I tried to ssh to it from my management node and I
> get:
> > [thardesty@vcl ~]$ ssh xxx.xx.xxx.xxx
> > Warning: Permanently added 'xxx.xx.xxx.xxx' (RSA) to the list of
> > known hosts.
> > Permission denied (publickey,keyboard-interactive).
> >
> > I can't seem to find what I am doing wrong so any help would be
> great!!
> >
> > -Tyler Hardesty
> >
> >
>
>
> - --
> Jim O'Dell
> Network Analyst
> California State University Fullerton
> Email: jodell@fullerton.edu
> Phone: (657) 278-2256
> -----BEGIN PGP SIGNATURE-----
> Version: GnuPG v1.4.9 (MingW32)
> Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/
>
> iEYEARECAAYFAk11fmsACgkQREVHAOnXPYTVQACgu6SjA9FSlGarenF22RM1l3uy
> 6w8AoKmvShBC4qwjDjSvCEavUBezdnXD
> =pF69
> -----END PGP SIGNATURE-----
>
Re: ssh on vmguest
Posted by James O'Dell <jo...@fullerton.edu>.
-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1
It really looks like your keys are incorrect.
try "ssh -vvv XXX.XXX.XXX.XXX", ssh will output debugging messages,
and you should be able to see why you're getting permission denied
__Jim
On 3/7/2011 4:48 PM, Tyler Hardesty wrote:
> Sorry, additional info---
> when I do:
> [root@vcl ~]# ssh -o CheckHostIP=no -o StrictHostKeyChecking=no -o
> BatchMode=no -i /etc/vcl/vcl.key xxx.xx.xxx.xxx
>
> It ssh's into the machine just fine.
>
> On Mon, Mar 7, 2011 at 5:41 PM, Tyler Hardesty <thardesty@gmail.com
> <ma...@gmail.com>> wrote:
>
> I've been trying to search through the mail archive but haven't
> found a solution to my problem... sorry if this was already covered
> and I just didn't find it...
> I'm trying to capture an image of a win xp vm and I get this error
> in the log file:
> |16224|3:3|image| ---- WARNING ----
> |16224|3:3|image| 2011-03-07
> 17:18:36|16224|3:3|image|VMware.pm:capture(462)|unable to capture
> image, VM vm2 is not responding to SSH
> |16224|3:3|image| ( 0) VMware.pm, capture (line: 462)
> |16224|3:3|image| (-1) image.pm <http://image.pm>, process (line: 165)
> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-3) vcld, main (line: 346)
> |16224|3:3|image| ---- WARNING ----
> |16224|3:3|image| 2011-03-07
> 17:18:36|16224|3:3|image|image.pm:process(169)|vmwarewinxp-windowsxp7-v0
> image failed to be captured by provisioning module
> |16224|3:3|image| ( 0) image.pm <http://image.pm>, process (line: 169)
> |16224|3:3|image| (-1) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-2) vcld, main (line: 346)
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> to retrieve private IP address for computer: vm2
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> contents of /etc/hosts on this management node, contains 7 lines
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> IP address from /etc/hosts file: 192.168.1.1
> 2011-03-07
> 17:18:36|16224|3:3|image|utils.pm:is_inblockrequest(6147)|zero rows
> were returned from database select
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> owner id: 1
> 2011-03-07
> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database requested
> (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:127.0.0.1)
> 2011-03-07
> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
> stored in $ENV{dbh}
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> to retrieve and store data for user: user.id <http://user.id> = '1'
> 2011-03-07
> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database requested
> (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:127.0.0.1)
> 2011-03-07
> 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database handle
> stored in $ENV{dbh}
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1416)|data
> has been retrieved for user: admin (id: 1)
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:mail(1268)|SUCCESS --
> Sending mail To: thardesty@tylerhardesty.info
> <ma...@tylerhardesty.info>, PROBLEM -- 3:3|image|image.pm
> <http://image.pm>|vm2>localvmhost|vmwarewinxp-windowsxp7-v0|admin
> |16224|3:3|image| ---- CRITICAL ----
> |16224|3:3|image| 2011-03-07
> 17:18:36|16224|3:3|image|image.pm:reservation_failed(393)|vmwarewinxp-windowsxp7-v0
> image creation failed
> |16224|3:3|image| ( 0) image.pm <http://image.pm>,
> reservation_failed (line: 393)
> |16224|3:3|image| (-1) image.pm <http://image.pm>, process (line: 170)
> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-3) vcld, main (line: 346)
>
> I've run the cygwin on the win xp vm and it was successfull...
> copying and pasting that command it shows at the end of the setup
> allowed me to ssh into it without having to put in a passwd or
> whatever so then I tried to ssh to it from my management node and I get:
> [thardesty@vcl ~]$ ssh xxx.xx.xxx.xxx
> Warning: Permanently added 'xxx.xx.xxx.xxx' (RSA) to the list of
> known hosts.
> Permission denied (publickey,keyboard-interactive).
>
> I can't seem to find what I am doing wrong so any help would be great!!
>
> -Tyler Hardesty
>
>
- --
Jim O'Dell
Network Analyst
California State University Fullerton
Email: jodell@fullerton.edu
Phone: (657) 278-2256
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.9 (MingW32)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/
iEYEARECAAYFAk11fmsACgkQREVHAOnXPYTVQACgu6SjA9FSlGarenF22RM1l3uy
6w8AoKmvShBC4qwjDjSvCEavUBezdnXD
=pF69
-----END PGP SIGNATURE-----
Re: ssh on vmguest
Posted by Tyler Hardesty <th...@gmail.com>.
Sorry, additional info---
when I do:
[root@vcl ~]# ssh -o CheckHostIP=no -o StrictHostKeyChecking=no -o
BatchMode=no -i /etc/vcl/vcl.key xxx.xx.xxx.xxx
It ssh's into the machine just fine.
On Mon, Mar 7, 2011 at 5:41 PM, Tyler Hardesty <th...@gmail.com> wrote:
> I've been trying to search through the mail archive but haven't found a
> solution to my problem... sorry if this was already covered and I just
> didn't find it...
> I'm trying to capture an image of a win xp vm and I get this error in the
> log file:
> |16224|3:3|image| ---- WARNING ----
> |16224|3:3|image| 2011-03-07
> 17:18:36|16224|3:3|image|VMware.pm:capture(462)|unable to capture image, VM
> vm2 is not responding to SSH
> |16224|3:3|image| ( 0) VMware.pm, capture (line: 462)
> |16224|3:3|image| (-1) image.pm, process (line: 165)
> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-3) vcld, main (line: 346)
> |16224|3:3|image| ---- WARNING ----
> |16224|3:3|image| 2011-03-07 17:18:36|16224|3:3|image|image.pm:process(169)|vmwarewinxp-windowsxp7-v0
> image failed to be captured by provisioning module
> |16224|3:3|image| ( 0) image.pm, process (line: 169)
> |16224|3:3|image| (-1) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-2) vcld, main (line: 346)
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1582)|attempting
> to retrieve private IP address for computer: vm2
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1586)|retrieved
> contents of /etc/hosts on this management node, contains 7 lines
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_computer_private_ip_address(1639)|returning
> IP address from /etc/hosts file: 192.168.1.1
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:is_inblockrequest(6147)|zero
> rows were returned from database select
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:get_image_affiliation_name(2029)|image
> owner id: 1
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
> requested (information_schema) does not match handle stored in $ENV{dbh}
> (vcl:127.0.0.1)
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1353)|attempting
> to retrieve and store data for user: user.id = '1'
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2709)|database
> requested (vcl) does not match handle stored in $ENV{dbh}
> (information_schema:127.0.0.1)
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:getnewdbh(2760)|database
> handle stored in $ENV{dbh}
> 2011-03-07
> 17:18:36|16224|3:3|image|DataStructure.pm:retrieve_user_data(1416)|data has
> been retrieved for user: admin (id: 1)
> 2011-03-07 17:18:36|16224|3:3|image|utils.pm:mail(1268)|SUCCESS -- Sending
> mail To: thardesty@tylerhardesty.info, PROBLEM -- 3:3|image|image.pm
> |vm2>localvmhost|vmwarewinxp-windowsxp7-v0|admin
> |16224|3:3|image| ---- CRITICAL ----
> |16224|3:3|image| 2011-03-07 17:18:36|16224|3:3|image|image.pm:reservation_failed(393)|vmwarewinxp-windowsxp7-v0
> image creation failed
> |16224|3:3|image| ( 0) image.pm, reservation_failed (line: 393)
> |16224|3:3|image| (-1) image.pm, process (line: 170)
> |16224|3:3|image| (-2) vcld, make_new_child (line: 568)
> |16224|3:3|image| (-3) vcld, main (line: 346)
>
> I've run the cygwin on the win xp vm and it was successfull... copying and
> pasting that command it shows at the end of the setup allowed me to ssh into
> it without having to put in a passwd or whatever so then I tried to ssh to
> it from my management node and I get:
> [thardesty@vcl ~]$ ssh xxx.xx.xxx.xxx
> Warning: Permanently added 'xxx.xx.xxx.xxx' (RSA) to the list of known
> hosts.
> Permission denied (publickey,keyboard-interactive).
>
> I can't seem to find what I am doing wrong so any help would be great!!
>
> -Tyler Hardesty
>
>