New VM instances are terminated | Eucalyptus Community

I am trying to experiment using Eucalyptus on a single laptop. However, each time I start a new instance with the command-line tools, it enters 'pending' state and soon moves to 'terminated'.

I have installed Eucalyptus 1.6.1 from source in Fedora 12. The laptop is the only node in the cluster, and I have selected the SYSTEM networking mode. As the hypervisor I am using KVM.

Here is the nc.log:

[Tue Dec 22 14:43:26 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:43:27 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:27 2009][019462][EUCADEBUG ] doStartNetwork() invoked
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] StartNetwork(): SUCCESS return from vnetStartNetwork 0
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] StartNetwork(): done
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] doRunInstance() invoked (id=i-48EC0824 cores=1 disk=2 memory=128)
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] image=emi-E59B1509 at http://130.230.57.124:8773/services/Walrus/centos-image-bucket/centos.5-...
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] krnel=eki-911C1774 at http://130.230.57.124:8773/services/Walrus/centos-kernel-bucket/vmlinuz-...
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] rmdsk=eri-F80918CA at http://130.230.57.124:8773/services/Walrus/centos-ramdisk-bucket/initrd....
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] vlan=10 priMAC=d0:0d:48:EC:08:24 pubMAC=d0:0d:48:EC:08:24
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] network started for instance i-48EC0824
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] retrieving images for instance i-48EC0824 (disk limit=2048MB)...
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] verifying cached file in /usr/local/eucalyptus/eucalyptus/cache/eki-911C1774/kernel...
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] walrus_request(): downloading /tmp/walrus-digest-Hbqzyu
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] from http://130.230.57.124:8773/services/Walrus/centos-kernel-bucket/vmlinuz-...
[Tue Dec 22 14:43:27 2009][019462][EUCADEBUG ] walrus_request(): writing GET output to /tmp/walrus-digest-Hbqzyu
[Tue Dec 22 14:43:27 2009][019462][EUCADEBUG ] walrus_request(): wrote 3455 bytes in 0 writes
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] walrus_request(): saved image in /tmp/walrus-digest-Hbqzyu
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] vrun(): [cp -a /usr/local/eucalyptus/eucalyptus/cache/eki-911C1774/kernel /usr/local/eucalyptus/admin/i-48EC0824/kernel]
[Tue Dec 22 14:43:27 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] verifying cached file in /usr/local/eucalyptus/eucalyptus/cache/eri-F80918CA/ramdisk...
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] walrus_request(): downloading /tmp/walrus-digest-p9rE8Q
[Tue Dec 22 14:43:27 2009][019462][EUCAINFO ] from http://130.230.57.124:8773/services/Walrus/centos-ramdisk-bucket/initrd....
[Tue Dec 22 14:43:28 2009][019462][EUCADEBUG ] walrus_request(): writing GET output to /tmp/walrus-digest-p9rE8Q
[Tue Dec 22 14:43:28 2009][019462][EUCADEBUG ] walrus_request(): wrote 3463 bytes in 0 writes
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] walrus_request(): saved image in /tmp/walrus-digest-p9rE8Q
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] vrun(): [cp -a /usr/local/eucalyptus/eucalyptus/cache/eri-F80918CA/ramdisk /usr/local/eucalyptus/admin/i-48EC0824/ramdisk]
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] verifying cached file in /usr/local/eucalyptus/eucalyptus/cache/emi-E59B1509/disk...
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] walrus_request(): downloading /tmp/walrus-digest-Z6RIGe
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] from http://130.230.57.124:8773/services/Walrus/centos-image-bucket/centos.5-...
[Tue Dec 22 14:43:28 2009][019462][EUCADEBUG ] walrus_request(): writing GET output to /tmp/walrus-digest-Z6RIGe
[Tue Dec 22 14:43:28 2009][019462][EUCADEBUG ] walrus_request(): wrote 6666 bytes in 0 writes
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] walrus_request(): saved image in /tmp/walrus-digest-Z6RIGe
[Tue Dec 22 14:43:28 2009][019462][EUCAINFO ] vrun(): [cp -a /usr/local/eucalyptus/eucalyptus/cache/emi-E59B1509/disk /usr/local/eucalyptus/admin/i-48EC0824/disk]
[Tue Dec 22 14:43:32 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:32 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:32 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:43:37 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:38 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:43:39 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:42 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:44 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:44 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:43:47 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:50 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:50 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:43:52 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:43:56 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:43:56 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:01 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:03 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:03 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:06 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:08 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:09 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:11 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:14 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:14 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:16 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:20 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:21 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:21 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:26 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:26 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:26 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:31 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:32 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:32 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:35 2009][019462][EUCAINFO ] vrun(): [/opt/eucalyptus/usr/lib/eucalyptus/euca_rootwrap /opt/eucalyptus/usr/share/eucalyptus/partition2disk /usr/local/eucalyptus/admin/i-48EC0824/disk 512 500]
[Tue Dec 22 14:44:36 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:38 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:38 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:41 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:43 2009][019462][EUCAERROR ] error: file /usr/local/eucalyptus/admin/i-48EC0824/disk not found
[Tue Dec 22 14:44:43 2009][019462][EUCAINFO ] preparing images for instance i-48EC0824...
[Tue Dec 22 14:44:43 2009][019462][EUCAINFO ] adding key/tmp/sckey.D7uGT2 to the root file system at /usr/local/eucalyptus/admin/i-48EC0824/disk using (/opt/eucalyptus/usr/lib/eucalyptus/euca_rootwrap /opt/eucalyptus/usr/share/eucalyptus/add_key.pl /opt/eucalyptus/usr/lib/eucalyptus/euca_mountwrap)
[Tue Dec 22 14:44:43 2009][019462][EUCAINFO ] vrun(): [/opt/eucalyptus/usr/lib/eucalyptus/euca_rootwrap /opt/eucalyptus/usr/share/eucalyptus/add_key.pl /opt/eucalyptus/usr/lib/eucalyptus/euca_mountwrap 32256 /usr/local/eucalyptus/admin/i-48EC0824/disk /tmp/sckey.D7uGT2]
[Tue Dec 22 14:44:44 2009][019462][EUCAERROR ] system(/opt/eucalyptus/usr/lib/eucalyptus/euca_rootwrap /opt/eucalyptus/usr/share/eucalyptus/add_key.pl /opt/eucalyptus/usr/lib/eucalyptus/euca_mountwrap 32256 /usr/local/eucalyptus/admin/i-48EC0824/disk /tmp/sckey.D7uGT2) failed with 256
[Tue Dec 22 14:44:44 2009][019462][EUCAERROR ] ERROR: key injection / tune2fs command failed
[Tue Dec 22 14:44:44 2009][019462][EUCADEBUG ] system_output(): [/opt/eucalyptus/usr/lib/eucalyptus/euca_rootwrap /opt/eucalyptus/usr/share/eucalyptus/gen_kvm_libvirt_xml --ramdisk --ephemeral]
[Tue Dec 22 14:44:44 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:44 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:45 2009][019462][EUCAINFO ] currently running/booting: i-48EC0824
[Tue Dec 22 14:44:48 2009][019462][EUCAERROR ] libvirt: monitor socket did not show up.: Connection refused (code=38)
[Tue Dec 22 14:44:48 2009][019462][EUCAFATAL ] hypervisor failed to start domain
[Tue Dec 22 14:44:48 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:48 2009][019462][EUCAINFO ] vrun(): [rm -rf /usr/local/eucalyptus/admin/i-48EC0824/]
[Tue Dec 22 14:44:48 2009][019462][EUCAINFO ] stopping the network (vlan=10)
[Tue Dec 22 14:44:50 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:50 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:56 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:56 2009][019462][EUCADEBUG ] doDescribeInstances() invoked
[Tue Dec 22 14:44:56 2009][019462][EUCAINFO ] doTerminateInstance() invoked (id=i-48EC0824)
[Tue Dec 22 14:44:56 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:56 2009][019462][EUCAWARN ] warning: domain i-48EC0824 to be terminated not running on hypervisor
[Tue Dec 22 14:44:56 2009][019462][EUCADEBUG ] doDescribeResource() invoked
[Tue Dec 22 14:44:58 2009][019462][EUCAERROR ] libvirt: Domain not found: no domain with matching name 'i-48EC0824' (code=42)
[Tue Dec 22 14:44:58 2009][019462][EUCAINFO ] vrun(): [rm -rf /usr/local/eucalyptus/admin/i-48EC0824/]
[Tue Dec 22 14:44:58 2009][019462][EUCAINFO ] stopping the network (vlan=10)
[Tue Dec 22 14:45:02 2009][019462][EUCADEBUG ] doDescribeInstances() invoked

The cc.log:

[Tue Dec 22 14:43:26 2009][019357][EUCADEBUG ] node 127.0.0.1 idle since 0: (1261485806/300) seconds
[Tue Dec 22 14:43:26 2009][019357][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:43:26 2009][020290][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] RunInstances(): called
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] node=127.0.0.1 mem=1805/1805 disk=3704/3704 cores=2/2
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] running instance i-48EC0824 with emiId emi-E59B1509...
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] assigning MAC/IP: d0:0d:48:EC:08:24/0.0.0.0/0.0.0.0/-1
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] scheduler using ROUNDROBIN policy to find next resource
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] scheduler state starting at resource 0
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] scheduler state finishing at resource 0
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] scheduler decided to run instance 'i-48EC0824' on resource 'http://127.0.0.1:8775/axis2/services/EucalyptusNC'
[Tue Dec 22 14:43:27 2009][020305][EUCAINFO ] call complete (pid/rc): 1211/0
[Tue Dec 22 14:43:27 2009][001211][EUCAINFO ] client (http://127.0.0.1:8775/axis2/services/EucalyptusNC) running instance: i-48EC0824 emi-E59B1509 d0:0d:48:EC:08:24 d0:0d:48:EC:08:24 10 ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDNk6/CiHfNGMx64+bfOQsQbzlQwhxw/x4UwAQ0d8LpvUUXJU0Fs6gKZOErYlOJ54K5N+nQWgQFlosy5Qijqxi+o3lQKkajg6wrb23GkHaaNBodNbUAHTH/PNJ9ViTivr0PhsQrbksQLEn70nl6VeuVEBWclOO7YotS5QqaI7ASwo0cUbD01D240dcEelHa0OTNK+SKIgb3aMlcmHpZCBILrbih0n8nIrz0BBn/i7UxTeaLrEAOlztYgWKeh9H/uxsGuoY9Sdlpb8gJ1YmoDMS4P0ge8674hmRztj3Pd2cZsadFpYdTItHEXKR72e5QmIl19C1pJAJiMoWXi+D5w035 admin@eucalyptus
[Tue Dec 22 14:43:27 2009][001211][EUCAINFO ] asking for virtual hardware (mem/disk/cores): 128/2/1
[Tue Dec 22 14:43:27 2009][020305][EUCADEBUG ] RunInstances(): done
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:43:32 2009][020300][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:43:32 2009][019359][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:43:32 2009][019359][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:32 2009][019359][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:43:32 2009][020300][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:43:32 2009][019358][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:32 2009][019358][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:32 2009][019359][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:43:32 2009][019359][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:43:32 2009][019359][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:43:34 2009][019359][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:43:34 2009][019359][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:43:34 2009][019359][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:43:38 2009][019357][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:43:38 2009][020305][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:43:38 2009][020305][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:38 2009][020305][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:43:38 2009][019355][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:43:38 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:38 2009][019355][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:38 2009][020305][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:43:38 2009][019357][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:43:38 2009][020305][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:43:38 2009][020305][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:43:39 2009][020305][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:43:39 2009][020305][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:43:39 2009][020305][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:43:39 2009][019355][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:39 2009][019355][EUCADEBUG ] time left for next op: 59
[Tue Dec 22 14:43:39 2009][019355][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:43:39 2009][019355][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:39 2009][019355][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:43:44 2009][019358][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:43:44 2009][019358][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:43:44 2009][020300][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:44 2009][020300][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:43:44 2009][019356][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:43:44 2009][019356][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:43:50 2009][019357][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:43:50 2009][019357][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:43:50 2009][019355][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:43:50 2009][019358][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:43:50 2009][019358][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:43:50 2009][019355][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:43:56 2009][019356][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:43:56 2009][019357][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:43:56 2009][019357][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:43:56 2009][019357][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:43:56 2009][019356][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:43:56 2009][020290][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:43:56 2009][020290][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:43:56 2009][019357][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:43:56 2009][019357][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:43:56 2009][019357][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:43:57 2009][019357][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:43:57 2009][019357][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:43:57 2009][019357][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:02 2009][019355][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:02 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:02 2009][019355][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:02 2009][019355][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:03 2009][019359][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:03 2009][019359][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:03 2009][019359][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:03 2009][020305][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:03 2009][019355][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:03 2009][019355][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:03 2009][020305][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:03 2009][019355][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:03 2009][019355][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:03 2009][019355][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:03 2009][019359][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:03 2009][019359][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:03 2009][019359][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:04 2009][019359][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:04 2009][019359][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:08 2009][020290][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:08 2009][019357][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:08 2009][019357][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:08 2009][019357][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:08 2009][019357][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:08 2009][020300][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:08 2009][020300][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:08 2009][020300][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:08 2009][020290][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:08 2009][019357][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:08 2009][019357][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:09 2009][019357][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:09 2009][019357][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:09 2009][019357][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:09 2009][020300][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:09 2009][020300][EUCADEBUG ] time left for next op: 59
[Tue Dec 22 14:44:09 2009][020300][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:09 2009][020300][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:09 2009][020300][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:14 2009][019355][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:14 2009][020290][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:14 2009][020290][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:14 2009][020290][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:14 2009][019355][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:14 2009][019359][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:14 2009][019359][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:14 2009][020290][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:14 2009][020290][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:15 2009][020290][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:15 2009][020290][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:15 2009][020290][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:20 2009][019357][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:20 2009][020300][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:20 2009][020300][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:20 2009][020300][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:20 2009][019355][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:20 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:20 2009][019355][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:20 2009][020300][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:20 2009][020300][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:20 2009][020300][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:20 2009][019357][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:21 2009][020300][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:21 2009][020300][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:21 2009][020300][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:21 2009][019355][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:21 2009][019355][EUCADEBUG ] time left for next op: 59
[Tue Dec 22 14:44:21 2009][019355][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:21 2009][019355][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:21 2009][019355][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:26 2009][019356][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:26 2009][019356][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:26 2009][020290][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:26 2009][020290][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:26 2009][019357][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:26 2009][019357][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:32 2009][020305][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:32 2009][019355][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:32 2009][019355][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:32 2009][020305][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:32 2009][019359][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:32 2009][019359][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:38 2009][020290][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:38 2009][019355][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:38 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:38 2009][019355][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:38 2009][019357][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:38 2009][019357][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:38 2009][019355][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:38 2009][020290][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:38 2009][019355][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:38 2009][019355][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:39 2009][019355][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:39 2009][019355][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:39 2009][019355][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:44 2009][019359][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:44 2009][020305][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Pending, 0
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:44 2009][019359][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] returning instance state: i-48EC0824/Pending
[Tue Dec 22 14:44:44 2009][020305][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:44 2009][019357][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:44 2009][019357][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:50 2009][020300][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:50 2009][019355][EUCAINFO ] node=127.0.0.1 mem=1805/1805 disk=3704/3704 cores=2/2
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:50 2009][020300][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:50 2009][019356][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Teardown, 0
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] returning instance state: i-48EC0824/Teardown
[Tue Dec 22 14:44:50 2009][019356][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:50 2009][019355][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:56 2009][019357][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] cache: i-48EC0824 0.0.0.0 0.0.0.0
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:44:56 2009][020305][EUCAINFO ] node=127.0.0.1 mem=1805/1805 disk=3704/3704 cores=2/2
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:44:56 2009][019357][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:44:56 2009][019358][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Teardown, 0
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] i-48EC0824 in cache
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] refreshing instance 'i-48EC0824'
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] returning instance state: i-48EC0824/Teardown
[Tue Dec 22 14:44:56 2009][019358][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:44:56 2009][020305][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] TerminateInstances(): called
[Tue Dec 22 14:44:56 2009][020300][EUCAINFO ] TerminateInstances(): calling terminate instance (i-48EC0824) on (127.0.0.1)
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] call complete (pid/rc): 1686/0
[Tue Dec 22 14:44:56 2009][020300][EUCAWARN ] failed to terminate 'i-48EC0824': instance may not exist any longer
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:44:56 2009][020300][EUCAINFO ] node=127.0.0.1 mem=1805/1677 disk=3704/3190 cores=2/1
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:44:56 2009][020300][EUCADEBUG ] TerminateInstances(): done.
[Tue Dec 22 14:45:02 2009][019356][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:45:02 2009][019355][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:45:02 2009][019355][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:45:02 2009][019355][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:45:02 2009][019355][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Teardown, 0
[Tue Dec 22 14:45:02 2009][019356][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:45:02 2009][019355][EUCADEBUG ] returning instance state: i-48EC0824/Teardown
[Tue Dec 22 14:45:02 2009][019355][EUCADEBUG ] DescribeInstances(): done
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] calling http://127.0.0.1:8775/axis2/services/EucalyptusNC
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] time left for next op: 60
[Tue Dec 22 14:45:02 2009][020290][EUCAINFO ] node=127.0.0.1 mem=1805/1805 disk=3704/3704 cores=2/2
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] refresh_resources(): done
[Tue Dec 22 14:45:02 2009][020290][EUCADEBUG ] DescribeResources(): done
[Tue Dec 22 14:45:08 2009][019358][EUCADEBUG ] printing instance cache in describeInstances()
[Tue Dec 22 14:45:08 2009][019358][EUCADEBUG ] DescribeInstances(): called
[Tue Dec 22 14:45:08 2009][020300][EUCADEBUG ] DescribeNetworks(): called
[Tue Dec 22 14:45:08 2009][019358][EUCADEBUG ] timeout(10/10)
[Tue Dec 22 14:45:08 2009][019359][EUCADEBUG ] DescribeResources(): called 5
[Tue Dec 22 14:45:08 2009][019359][EUCADEBUG ] refresh_resources(): called
[Tue Dec 22 14:45:08 2009][019358][EUCAINFO ] DescribeInstances(): describing instance i-48EC0824, Teardown, 0
[Tue Dec 22 14:45:08 2009][020300][EUCADEBUG ] DescribeNetworks(): done
[Tue Dec 22 14:45:08 2009][019358][EUCADEBUG ] returning instance state: i-48EC0824/Teardown
[Tue Dec 22 14:45:08 2009][019358][EUCADEBUG ] DescribeInstances(): done

The cloud-output.log:

14:43:14 INFO ClusterUtil 14:43:14 WARN NetworkStateHandler 14:43:20 INFO ClusterUtil 14:43:20 INFO ClusterUtil 14:43:20 INFO ClusterUtil 14:43:20 INFO ClusterUtil 14:43:20 WARN NetworkStateHandler 14:43:25 INFO ServiceSinkHandler 14:43:26 INFO ImageManager 14:43:26 INFO ImageManager 14:43:26 INFO ImageManager 14:43:26 INFO ImageManager 14:43:26 INFO ImageManager 14:43:26 INFO ImageManager 14:43:26 INFO ClusterUtil 14:43:26 INFO ClusterUtil 14:43:26 INFO ClusterUtil 14:43:26 INFO ClusterUtil 14:43:26 WARN NetworkStateHandler 14:43:27 INFO SLAs 14:43:27 INFO ReplyQueue 14:43:27 INFO ServiceSinkHandler 14:43:27 INFO WalrusRESTBinding 14:43:27 INFO ServiceSinkHandler 14:43:27 INFO WalrusStatistics 14:43:27 INFO ReplyQueue 14:43:28 INFO WalrusRESTBinding 14:43:28 INFO ServiceSinkHandler 14:43:28 INFO WalrusStatistics 14:43:28 INFO ReplyQueue 14:43:28 INFO WalrusRESTBinding 14:43:28 INFO ServiceSinkHandler 14:43:28 INFO WalrusStatistics 14:43:28 INFO ReplyQueue 14:43:32 INFO ClusterUtil 14:43:32 INFO ClusterUtil 14:43:32 INFO ClusterUtil 14:43:32 INFO ClusterUtil 14:43:32 WARN NetworkStateHandler 14:43:38 INFO ClusterUtil 14:43:38 INFO ClusterUtil 14:43:38 INFO ClusterUtil 14:43:38 INFO ClusterUtil 14:43:38 WARN NetworkStateHandler 14:43:44 INFO ClusterUtil 14:43:44 INFO ClusterUtil 14:43:44 INFO ClusterUtil 14:43:44 INFO ClusterUtil 14:43:44 WARN NetworkStateHandler 14:43:50 INFO ClusterUtil 14:43:50 INFO ClusterUtil 14:43:50 INFO ClusterUtil 14:43:50 INFO ClusterUtil 14:43:50 WARN NetworkStateHandler 14:43:56 INFO ClusterUtil 14:43:56 INFO ClusterUtil 14:43:56 INFO ClusterUtil 14:43:56 INFO ClusterUtil 14:43:56 WARN NetworkStateHandler 14:44:02 INFO ClusterUtil 14:44:02 INFO ClusterUtil 14:44:02 INFO ClusterUtil 14:44:02 INFO ClusterUtil 14:44:02 WARN NetworkStateHandler 14:44:08 INFO ClusterUtil 14:44:08 INFO ClusterUtil 14:44:08 INFO ClusterUtil 14:44:08 INFO ClusterUtil 14:44:08 WARN NetworkStateHandler 14:44:14 INFO ClusterUtil 14:44:14 INFO ClusterUtil 14:44:14 INFO ClusterUtil 14:44:14 INFO ClusterUtil 14:44:14 WARN NetworkStateHandler 14:44:20 INFO ClusterUtil 14:44:20 INFO ClusterUtil 14:44:20 INFO ClusterUtil 14:44:20 INFO ClusterUtil 14:44:20 WARN NetworkStateHandler 14:44:26 INFO ClusterUtil 14:44:26 INFO ClusterUtil 14:44:26 INFO ClusterUtil 14:44:26 INFO ClusterUtil 14:44:26 WARN NetworkStateHandler 14:44:32 INFO ClusterUtil 14:44:32 INFO ClusterUtil 14:44:32 INFO ClusterUtil 14:44:32 INFO ClusterUtil 14:44:32 WARN NetworkStateHandler 14:44:38 INFO ClusterUtil 14:44:38 INFO ClusterUtil 14:44:38 INFO ClusterUtil 14:44:38 INFO ClusterUtil 14:44:38 WARN NetworkStateHandler 14:44:44 INFO ClusterUtil 14:44:44 INFO ClusterUtil 14:44:44 INFO ClusterUtil 14:44:44 INFO ClusterUtil 14:44:44 WARN NetworkStateHandler 14:44:50 INFO ClusterUtil 14:44:50 INFO ClusterUtil 14:44:50 INFO ClusterUtil 14:44:50 INFO ClusterUtil 14:44:50 WARN NetworkStateHandler 14:44:50 INFO VmInstance 14:44:56 INFO ClusterUtil 14:44:56 INFO ClusterUtil 14:44:56 INFO ClusterUtil 14:44:56 INFO ClusterUtil 14:44:56 WARN NetworkStateHandler 14:44:56 INFO VmInstance | ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| :1261485824.0000:eucalyptus/ServiceSinkHandler.handleUpstream.192:eucalyptus:eucalyptus:MSG_RECEIVED:RunInstancesType:
| Checking image: centos-kernel-bucket/vmlinuz-2.6.28-11-server.manifest.xml
| Triggering caching: centos-kernel-bucket/vmlinuz-2.6.28-11-server.manifest.xml
| Checking image: centos-image-bucket/centos.5-3.x86.img.manifest.xml
| Triggering caching: centos-image-bucket/centos.5-3.x86.img.manifest.xml
| Checking image: centos-ramdisk-bucket/initrd.img-2.6.28-11-server.manifest.xml
| Triggering caching: centos-ramdisk-bucket/initrd.img-2.6.28-11-server.manifest.xml
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| [ accepted] NetworkToken [cluster=fast_test_cluster, indexes=[], name=admin-default, networkName=default, userName=admin, vlan=10]
| Message: edu.ucsb.eucalyptus.msgs.RunInstancesResponseType:da5685e8-304a-4c98-85ed-c5f501a0ee9a
| :1261485824.0000:eucalyptus/ServiceSinkHandler.sendDownstreamNewEvent.164:admin:da5685e8-304a-4c98-85ed-c5f501a0ee9a:MSG_SERVICED:RunInstancesResponseType:
| <?xml version="1.0" encoding="UTF-8"?>
|
|
|
|
| 0bd708c7-0682-4740-a4b3-5c85164fd60c
| WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw
| 2009-12-22T12:43:27.563Z
|
| centos-kernel-bucket
| vmlinuz-2.6.28-11-server.manifest.xml
|
|
| true
| true
| false
|
| :1261485824.0000:eucalyptus/ServiceSinkHandler.handleUpstream.192:eucalyptus:eucalyptus:MSG_RECEIVED:GetObjectType:
| Service: Walrus Version: 1.6.1 Bytes In: 245931104 Bytes Out: 29382 Buckets: 9 Space Used: 915910462
| Message: edu.ucsb.eucalyptus.msgs.GetObjectResponseType:0bd708c7-0682-4740-a4b3-5c85164fd60c
| <?xml version="1.0" encoding="UTF-8"?>
|
|
|
|
| d302d45a-0b29-46d2-8209-2092e423e2c7
| WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw
| 2009-12-22T12:43:28.026Z
|
| centos-ramdisk-bucket
| initrd.img-2.6.28-11-server.manifest.xml
|
|
| true
| true
| false
|
| :1261485824.0000:eucalyptus/ServiceSinkHandler.handleUpstream.192:eucalyptus:eucalyptus:MSG_RECEIVED:GetObjectType:
| Service: Walrus Version: 1.6.1 Bytes In: 245931104 Bytes Out: 32845 Buckets: 9 Space Used: 915910462
| Message: edu.ucsb.eucalyptus.msgs.GetObjectResponseType:d302d45a-0b29-46d2-8209-2092e423e2c7
| <?xml version="1.0" encoding="UTF-8"?>
|
|
|
|
| 5cfcd1f1-c9cb-4141-9156-16c8852a46a2
| WKy3rMzOWPouVOxK1p3Ar1C2uRBwa2FBXnCw
| 2009-12-22T12:43:28.517Z
|
| centos-image-bucket
| centos.5-3.x86.img.manifest.xml
|
|
| true
| true
| false
|
| :1261485824.0000:eucalyptus/ServiceSinkHandler.handleUpstream.192:eucalyptus:eucalyptus:MSG_RECEIVED:GetObjectType:
| Service: Walrus Version: 1.6.1 Bytes In: 245931104 Bytes Out: 39511 Buckets: 9 Space Used: 915910462
| Message: edu.ucsb.eucalyptus.msgs.GetObjectResponseType:5cfcd1f1-c9cb-4141-9156-16c8852a46a2
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| i-48EC0824 state change: PENDING -> SHUTTING_DOWN
| ---------------------------------------------------------------
| -> [ fast_test_cluster ] Cluster certificate valid=true
| -> [ fast_test_cluster ] Node certificate valid=true
| ---------------------------------------------------------------
| Response from cluster [fast_test_cluster]: NOTSUPPORTED
| i-48EC0824 state change: SHUTTING_DOWN -> TERMINATED

Here is the log printed by the hypervisor (in /var/log/libvirt/qemu/i-48EC0824.log):

LC_ALL=C PATH=/sbin:/usr/sbin:/bin:/usr/bin /usr/bin/kvm -S -M pc-0.11 -m 128 -smp 1 -name i-48EC0824 -uuid 4530986a-813b-047b-c3e3-e857edb3aa9f -nographic -monitor unix:/var/lib/libvirt/qemu/i-48EC0824.monitor,server,nowait -boot c -kernel /usr/local/eucalyptus/admin/i-48EC0824/kernel -initrd /usr/local/eucalyptus/admin/i-48EC0824/ramdisk -append root=/dev/sda1 console=ttyS0 -drive file=/usr/local/eucalyptus/admin/i-48EC0824/disk,if=scsi,index=0,boot=on -net nic,macaddr=d0:0d:48:ec:08:24,vlan=0,model=e1000,name=e1000.0 -net tap,fd=17,vlan=0,name=tap.0 -serial file:/usr/local/eucalyptus/admin/i-48EC0824/console.log -parallel none -usb
qemu: could not open serial device 'file:/usr/local/eucalyptus/admin/i-48EC0824/console.log': Permission denied

Tue, 12/22/2009 - 08:45

neil

Offline

Joined: 04/28/2009

Re: New VM instances are terminated

Does your laptop have hardware virtualization support? Is it turned on in the BIOS? The default instance config that is generated will work with kvm with VT extensions only.

Try running "kvm-ok"

Wed, 12/23/2009 - 03:53

kre

Joined: 12/18/2009

Thank you for the reply. I have checked that the CPU has a VT capable Intel processor by running "grep vmx /proc/cpuinfo"; the 'vmx' flag is included in the output. I have also enabled the virtualization support in the BIOS settings earlier when I encountered some different error messages.

I can't find the "kvm-ok" script on my computer. However, I can run "modprobe kvm_intel" without errors.

I have also been able to start virtual machine instances manually using the virsh command-line program as the root user. However, when logged in as the 'eucalyptus' user, I have to connect to qemu:///system with the root password before I create a virtual machine instance; otherwise I cannot see the instance in the Virtual Machine Manager GUI application.

I have also set the MANUAL_INSTANCES_CLEANUP in eucalyptus.conf to 1 so that the XML configuration files of the instances can be recovered. I tried running the instances manually using virsh. However, it seems that the XML configuration files must first be modified. Otherwise using virsh results in the same error message as can be seen in the nc.log: "error: monitor socket did not show up.: Connection refused". This problem is probably because the instance cannot write to the file /usr/local/eucalyptus///console.log which is specified in the configuration file, and which cannot be found in the instance directory. If I create this file manually and run "chmod o+w console.log" this problem is solved. However, according to the output printed in the file, the root file system '/dev/sda1' cannot be found, and the instance drops to an error recovery shell.

Fri, 03/19/2010 - 22:15

jeevanullas

Joined: 02/12/2010

eucalyptus user fail to connect

Hi,

Today I installed eucalyptus 1.6.2 on a fedora 12 laptop via source. But when I run the instance I see

[Sat Mar 20 11:43:22 2010][008310][EUCADEBUG ] doStartNetwork() invoked
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] StartNetwork(): SUCCESS return from vnetStartNetwork 0
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] StartNetwork(): done
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] doRunInstance() invoked (id=i-51AC0A53 cores=1 disk=2 memory=128)
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] image=emi-56E61678 at http://192.168.1.2:8773/services/Walrus/ubuntu-9.04-i386-fs-bucket/ubunt...
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] krnel=eki-8488171E at http://192.168.1.2:8773/services/Walrus/ubuntu-9.04-i386-bucket/vmlinuz-...
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] rmdsk=eri-BB8B1B15 at http://192.168.1.2:8773/services/Walrus/ubuntu-9.04-i386-ramdisk-bucket/...
[Sat Mar 20 11:43:22 2010][008310][EUCAINFO ] vlan=-1 priMAC=d0:0d:51:AC:0A:53 pubMAC=d0:0d:51:AC:0A:53
[Sat Mar 20 11:43:23 2010][008310][EUCAFATAL ] Failed to connect to qemu:///system
[Sat Mar 20 11:43:23 2010][008310][EUCAFATAL ] could not start instance i-51AC0A53, abandoning it
[Sat Mar 20 11:43:23 2010][008310][EUCADEBUG ] doDescribeResource() invoked
[Sat Mar 20 11:43:23 2010][008310][EUCADEBUG ] doDescribeInstances() invoked
[Sat Mar 20 11:43:24 2010][008310][EUCAFATAL ] Failed to connect to qemu:///system
[Sat Mar 20 11:43:24 2010][008310][EUCAINFO ] vrun(): [rm -rf /usr/local/eucalyptus/admin/i-51AC0A53/]
[Sat Mar 20 11:43:24 2010][008310][EUCAINFO ] stopping the network (vlan=-1)
[Sat Mar 20 11:43:29 2010][008310][EUCAFATAL ] Failed to connect to qemu:///system

Which means the eucalyptus user in my system doesn't have access to qemu:///system. I am using KVM with hardware VT.

Can you give some pointers?

Regards
Deependra Singh Shekhawat

Sat, 03/27/2010 - 13:15

console.log permission denied

Hi kre,

Were you able to find a fix for your problem regarding console.log permission denied error? Creating the file manually and giving a+w works but that is just a work around as you said. I have a directory set for the instances with owner and group eucalyptus but still it fails to write console.log file. Have to manually create it.

Any ideas ?

Wed, 03/31/2010 - 14:07

graziano

Joined: 01/14/2010

Hello, I assume you have F12

Hello,

I assume you have F12 install, since you posted on this thread. You can try to modify your partition2disk script: add something like

# we need to be sure that the console.log is writable by the qemu user my $qemu = untaint(`grep qemu /etc/passwd|cut -f 1 -d ':'`); if ( !$qemu) { $qemu = "eucalyptus"; }; chomp($qemu); my $console_log = $partition; $console_log =~ s|(.*)/(.*)|$1/console.log|; untaint(`touch $console_log`); untaint(`chown $qemu.eucalyptus $console_log`); untaint(`chmod 0660 $console_log`);

before the my $magic line.

Let us know if this works for you.

cheers
graziano

Wed, 03/31/2010 - 14:25

Hello, you should try to run

you should try to run virsh list as the eucalyptus user (or run an instace as the eucalyptus user): if you don't have the permission, you may need to modify libvirtd.conf and have (uncommented) line like
unix_sock_group = "libvirt" unix_sock_rw_perms = "0770" auth_unix_ro = "none" auth_unix_rw = "none"

I'm away from my test machines so I don't remember the group: check it . Also make sure that eucalyptus is part of that group.

Let us know how it goes.

Thu, 04/01/2010 - 11:02

it works

It works like a charm :)

Thanks for the patch.

Wed, 04/07/2010 - 01:34

sreeharsha.tcs

Joined: 03/10/2010

Error due to SELinux

qemu: could not open serial device 'file:/usr/local/eucalyptus/admin/i-48EC0824/console.log': Permission denied

It may also be due to SELinux, You may have to change it to permissive mode if you get this error. To change it temporarily to permissive mode:
echo 0 > /selinux/enforce

You need to be a root user to run the above command