The target IP is already in use by the VM that is being prepared.
2014-10-09 18:35 GMT+01:00 Mike Haudenschild <mhau...@gmail.com>:
> The point of the ping is to ensure the IP's not already in use, so if that
> IP *is* in fact already being used, bypassing this test may allow the VM
> provisioning to proceed, but there would be an IP address conflict on the
> network. I would first check to see if this error is, in fact, legit --
> when you get an error like this, check to see if the target IP is
> *actually* already in use somewhere on the network.
>
> On Thu, Oct 9, 2014 at 1:19 PM, António Aragão <aara...@di.uminho.pt>
> wrote:
>
>> There is only one dhcpd server. It only send ips for the private ip, the
>> public ip is static in VCL. This problem only occurs on Linux images. I
>> never see this problem on Windows images. The public ip is already set. Is
>> there any option to skip ping test ?
>>
>> 2014-10-09 16:06 GMT+01:00 Mike Haudenschild <mhau...@gmail.com>:
>>
>>> This looks like a possible IP address conflict. From the log:
>>>
>>> |19461|265:265|new| ---- WARNING ----
>>> |19461|265:265|new| 2014-10-09 15:07:11|19461|265:265|new|
>>> Ubuntu.pm:set_static_public_address(1000)|ip_address 192.168.93.128 is
>>> pingable, can not assign to vm28
>>>
>>> It's possible that mass-reloading the VMs is resulting in new and proper
>>> DHCP requests/acknowledgments going out. Is there more than one DHCP
>>> server with this IP range in-scope?
>>>
>>> You could also set a static IP in VCL directly and avoid using DHCP for
>>> the public interface altogether, in which case you'd need to remove the IP
>>> range from the DHCP scope.
>>>
>>> On Thu, Oct 9, 2014 at 10:43 AM, António Aragão <aara...@di.uminho.pt>
>>> wrote:
>>>
>>>> Hi,
>>>>
>>>> this happens a lots with Linux images. Sometimes I reload a bunch of
>>>> virtual machines and it solves temporarily the problem.
>>>>
>>>> Any clue ?
>>>>
>>>> ---------- Forwarded message ----------
>>>> From: <r...@vclnode1.apachevcl.di.uminho.pt>
>>>> Date: 2014-10-09 15:07 GMT+01:00
>>>> Subject: PROBLEM -- vclnode1|265:265|new|State.pm|vm28>
>>>> servidor12.di.uminho.pt
>>>> |vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0|vcl_a70373
>>>> To: aara...@di.uminho.pt
>>>>
>>>>
>>>> reservation failed on vm28: failed to update public IP address
>>>> ------------------------------------------------------------------------
>>>> time: 2014-10-09 15:07:11
>>>> caller: State.pm:reservation_failed(240)
>>>> ( 0) State.pm, reservation_failed (line: 240)
>>>> (-1) new.pm, confirm_public_ip_address (line: 1324)
>>>> (-2) new.pm, reserve_computer (line: 950)
>>>> (-3) new.pm, process (line: 408)
>>>> (-4) vcld, make_new_child (line: 571)
>>>> (-5) vcld, main (line: 350)
>>>> ------------------------------------------------------------------------
>>>> management node: vclnode1.apachevcl.di.uminho.pt
>>>> reservation PID: 19461
>>>> parent vcld PID: 1888
>>>>
>>>> request ID: 265
>>>> reservation ID: 265
>>>> request state/laststate: new/new
>>>> request start time: 2014-10-09 15:00:00
>>>> request end time: 2014-10-09 16:15:00
>>>> for imaging: no
>>>> log ID: 172
>>>>
>>>> computer: vm28.apachevcl.di.uminho.pt
>>>> computer id: 32
>>>> computer type: virtualmachine
>>>> computer eth0 MAC address: 00:50:56:00:00:36
>>>> computer eth1 MAC address: 00:50:56:00:00:37
>>>> computer private IP address: 10.100.0.128
>>>> computer public IP address: 192.168.93.128
>>>> computer in block allocation: no
>>>> provisioning module: VCL::Module::Provisioning::VMware::VMware
>>>>
>>>> vm host: servidor12.di.uminho.pt
>>>> vm host ID: 1
>>>> vm host computer ID: 1
>>>> vm profile: vmhostprofile1
>>>> vm profile VM path: /vmfs/volumes/datastore1/
>>>> vm profile repository path: /vmfs/volumes/vcl_images/
>>>> vm profile datastore path: /vmfs/volumes/datastore1/
>>>> vm profile disk type: shared
>>>>
>>>> image: vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0
>>>> image display name: UBUNTU1204_20140403_VMWARE_00001
>>>> image ID: 74
>>>> image revision ID: 70
>>>> image size: 13682 MB
>>>> use Sysprep: yes
>>>> root access: yes
>>>> image owner ID: 1
>>>> image owner affiliation: Local
>>>> image revision date created: 2014-04-03 10:39:09
>>>> image revision production: yes
>>>> OS module: VCL::Module::OS::Linux::Ubuntu
>>>>
>>>> user: vcl_a70373
>>>> user name:
>>>> user ID: 56
>>>> user affiliation: DIALUNOSLDAP
>>>> ------------------------------------------------------------------------
>>>> RECENT LOG ENTRIES FOR THIS PROCESS:
>>>> |19461|265:265|new| /usr/bin/ssh -i /etc/vcl/vcl.key -o
>>>> StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -o
>>>> ConnectionAttempts=1 -o ConnectTimeout=3 -l root -p 22 -x vm28 'cat
>>>> /proc/cpuinfo' 2>&1
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|utils.pm:run_ssh_command(5020)|run_ssh_command
>>>> output:
>>>> |19461|265:265|new| processor : 0
>>>> |19461|265:265|new| vendor_id : AuthenticAMD
>>>> |19461|265:265|new| cpu family : 21
>>>> |19461|265:265|new| model : 2
>>>> |19461|265:265|new| model name : AMD Opteron(tm) Processor 6380
>>>> |19461|265:265|new| stepping : 0
>>>> |19461|265:265|new| microcode : 0x600081f
>>>> |19461|265:265|new| cpu MHz : 2500.000
>>>> |19461|265:265|new| cache size : 2048 KB
>>>> |19461|265:265|new| fdiv_bug : no
>>>> |19461|265:265|new| hlt_bug : no
>>>> |19461|265:265|new| f00f_bug : no
>>>> |19461|265:265|new| coma_bug : no
>>>> |19461|265:265|new| fpu : yes
>>>> |19461|265:265|new| fpu_exception : yes
>>>> |19461|265:265|new| cpuid level : 13
>>>> |19461|265:265|new| wp : yes
>>>> |19461|265:265|new| flags : fpu vme de pse tsc msr pae mce cx8 apic sep
>>>> mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx mmxext
>>>> fxsr_opt rdtscp lm constant_tsc up tsc_reliable nonstop_tsc aperfmperf pni
>>>> cx16 popcnt hypervisor lahf_lm extapic abm sse4a misalignsse 3dnowprefetch
>>>> arat
>>>> |19461|265:265|new| bogomips : 5000.00
>>>> |19461|265:265|new| clflush size : 64
>>>> |19461|265:265|new| cache_alignment : 64
>>>> |19461|265:265|new| address sizes : 40 bits physical, 48 bits virtual
>>>> |19461|265:265|new| power management:
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:run_ssh_command(5034)|SSH
>>>> command executed on vm28, returning (0, "processor : 0 vendor_id :
>>>> Auth...")
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|Linux.pm:get_cpu_core_count(3435)|retrieved vm28
>>>> CPU core count: 1
>>>> |19461|265:265|new| cpuinfo 'processor' line count: 1
>>>> |19461|265:265|new| cpuinfo 'cpu cores': 1
>>>> |19461|265:265|new| cpuinfo 'siblings': 1
>>>> |19461|265:265|new| hyperthreading enabled: no
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|VMware.pm:get_vm_cpu_configuration(5163)|VM CPU
>>>> configuration:
>>>> |19461|265:265|new| sockets: 1
>>>> |19461|265:265|new| cores per socket: 1
>>>> |19461|265:265|new| total cores: 1
>>>> 2014-10-09 15:07:11|19461|265:265|new|VMware.pm:load(547)|verified VM
>>>> OS recognized correct number of CPUs: 1
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|new.pm:reload_image(672)|vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0
>>>> was successfully reloaded on vm28
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:insertloadlog(3665)|inserted
>>>> computer=32, loadimagecomplete,
>>>> vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0 was successfully
>>>> reloaded on vm28
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|utils.pm:update_currentimage(5685)|updating
>>>> computer 32: image=74, imagerevision=70
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|utils.pm:update_currentimage(5715)|updated
>>>> currentimageid and imagerevision id for computer id 32
>>>> 2014-10-09 15:07:11|19461|265:265|new|new.pm:reload_image(683)|updated
>>>> computer table for vm28: currentimageid=74
>>>> 2014-10-09 15:07:11|19461|265:265|new|new.pm:reload_image(703)|returning
>>>> 1
>>>> 2014-10-09 15:07:11|19461|265:265|new|new.pm:process(292)|vm28 is
>>>> loaded with vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:insertloadlog(3665)|inserted
>>>> computer=32, nodeready, vm28 is loaded with
>>>> vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|new.pm:process(374)|request_state_name=
>>>> new
>>>> 2014-10-09 15:07:11|19461|265:265|new|new.pm:process(386)|vm28 next
>>>> image is already set to vmwareubuntu-UBUNTU1204_20140403_VMWARE_0000174-v0
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:is_parent_reservation(1004)|returning
>>>> true: parent reservation ID for this request: 265
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|new.pm:reserve_computer(936)|user_standalone=1,
>>>> image OS type=linux
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|OS.pm:get_public_interface_name(1150)|returning
>>>> public interface name previously retrieved: eth1
>>>> |19461|265:265|new| ---- WARNING ----
>>>> |19461|265:265|new| 2014-10-09
>>>> 15:07:11|19461|265:265|new|OS.pm:get_ip_address(1531)|unable to determine
>>>> public IP address, 'ip_address' value is not set in the network
>>>> configuration info:
>>>> |19461|265:265|new| : {
>>>> |19461|265:265|new| : "ip_address" => {},
>>>> |19461|265:265|new| : "name" => "eth1",
>>>> |19461|265:265|new| : "physical_address" => "00:50:56:00:00:37"
>>>> |19461|265:265|new| : }
>>>> |19461|265:265|new| ( 0) OS.pm, get_ip_address (line: 1531)
>>>> |19461|265:265|new| (-1) OS.pm, get_public_ip_address (line: 1587)
>>>> |19461|265:265|new| (-2) new.pm, confirm_public_ip_address (line: 1301)
>>>> |19461|265:265|new| (-3) new.pm, reserve_computer (line: 950)
>>>> |19461|265:265|new| (-4) new.pm, process (line: 408)
>>>> |19461|265:265|new| (-5) vcld, make_new_child (line: 571)
>>>> |19461|265:265|new| ---- WARNING ----
>>>> |19461|265:265|new| 2014-10-09
>>>> 15:07:11|19461|265:265|new|new.pm:confirm_public_ip_address(1322)|failed
>>>> to retrieve dynamic public IP address from vm28
>>>> |19461|265:265|new| ( 0) new.pm, confirm_public_ip_address (line: 1322)
>>>> |19461|265:265|new| (-1) new.pm, reserve_computer (line: 950)
>>>> |19461|265:265|new| (-2) new.pm, process (line: 408)
>>>> |19461|265:265|new| (-3) vcld, make_new_child (line: 571)
>>>> |19461|265:265|new| (-4) vcld, main (line: 350)
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|OS.pm:update_public_ip_address(980)|IP
>>>> configuration is set to static, attempting to set public IP address
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|OS.pm:get_public_interface_name(1150)|returning
>>>> public interface name previously retrieved: eth1
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:is_valid_ip_address(9013)|IP
>>>> address is valid: 192.168.93.254
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:get_management_node_public_default_gateway(2271)|returning
>>>> default gateway configured in vcld.conf: 192.168.93.254
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|Ubuntu.pm:set_static_public_address(994)|attempting
>>>> to set static public IP address on vm28
>>>> |19461|265:265|new| ---- WARNING ----
>>>> |19461|265:265|new| 2014-10-09
>>>> 15:07:11|19461|265:265|new|Ubuntu.pm:set_static_public_address(1000)|ip_address
>>>> 192.168.93.128 is pingable, can not assign to vm28
>>>> |19461|265:265|new| ( 0) Ubuntu.pm, set_static_public_address (line:
>>>> 1000)
>>>> |19461|265:265|new| (-1) OS.pm, update_public_ip_address (line: 984)
>>>> |19461|265:265|new| (-2) new.pm, confirm_public_ip_address (line: 1324)
>>>> |19461|265:265|new| (-3) new.pm, reserve_computer (line: 950)
>>>> |19461|265:265|new| (-4) new.pm, process (line: 408)
>>>> |19461|265:265|new| (-5) vcld, make_new_child (line: 571)
>>>> |19461|265:265|new| ---- WARNING ----
>>>> |19461|265:265|new| 2014-10-09
>>>> 15:07:11|19461|265:265|new|OS.pm:update_public_ip_address(989)|failed to
>>>> set static public IP address on vm28
>>>> |19461|265:265|new| ( 0) OS.pm, update_public_ip_address (line: 989)
>>>> |19461|265:265|new| (-1) new.pm, confirm_public_ip_address (line: 1324)
>>>> |19461|265:265|new| (-2) new.pm, reserve_computer (line: 950)
>>>> |19461|265:265|new| (-3) new.pm, process (line: 408)
>>>> |19461|265:265|new| (-4) vcld, make_new_child (line: 571)
>>>> |19461|265:265|new| (-5) vcld, main (line: 350)
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:insertloadlog(3665)|inserted
>>>> computer=32, staticIPaddress, failed to set static public IP address on
>>>> vm28
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:get_computer_state_name(2436)|attempting
>>>> to retrieve current state of computer vm28 from the database
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:get_computer_state_name(2467)|retrieved
>>>> current state of computer vm28 from the database: reloading
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:_automethod(847)|data structure
>>>> updated, hash path:
>>>> $self->request_data->{reservation}{265}{computer}{state}{name}, data
>>>> identifier: computer_state_name, data:
>>>> |19461|265:265|new| : "reloading"
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:get_computer_private_ip_address(1617)|returning
>>>> private IP address previously retrieved: 10.100.0.128
>>>> 2014-10-09 15:07:11|19461|265:265|new|utils.pm:is_inblockrequest(5760)|zero
>>>> rows were returned from database select
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:get_image_affiliation_name(2121)|image
>>>> owner id: 1
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:retrieve_user_data(1412)|attempting
>>>> to retrieve and store data for user: user.id = '1'
>>>> 2014-10-09
>>>> 15:07:11|19461|265:265|new|DataStructure.pm:retrieve_user_data(1475)|data
>>>> has been retrieved for user: admin (id: 1)
>>>>
>>>>
>>>>
>>>> --
>>>> http://www.di.uminho.pt/~apa/email/cartao_virtual_email_aaragao.png
>>>>
>>>
>>>
>>
>>
>> --
>> http://www.di.uminho.pt/~apa/email/cartao_virtual_email_aaragao.png
>>
>
>
--
http://www.di.uminho.pt/~apa/email/cartao_virtual_email_aaragao.png
