windows xp instance problems when using nova under ubuntu 10.4 lts 64bit

Asked by arturo lorenzo

Trying to start a windows instance under nova (ubuntu 10.4 lts 64bit) and get errors when launching the instance. The status goes from launching to pending and stays in pending forever. Tried all tricks to connect via VNC and to ignore kernel and ramdisk unsuccessfully. I can run linux instances successfully and also the same windows image can be run successfully via KVM.
Created the image with euca tools provided by the nova stack and using this URL as a reference:
http://kiranmurari.wordpress.com/2010/03/29/uec-bundling-windows-image/
The first I've noticed was when registering the memdisk I did not get an EKI-xxxxxxx I got an AMI-xxxxxxx and the same with ramdisk.
But I was able to continue and bundle the image successfully using the AMIs from kernel and ramdisk.
Here is the list of my images:
==========================================================================
root@ubuntu-svr2:/home/alorenzo/windowsxp# euca-describe-images
IMAGE ami-ol70fuy6 mybucket/machine.manifest.xml IRT available private x86_64 machine ami-9bsb0d3n ami-8lw4w0x5
IMAGE ami-qhvha7sg winxp_bucket/memdisk.manifest.xml IRT available private x86_64 kernel true
IMAGE ami-8lw4w0x5 mybucket/ramdisk.manifest.xml IRT available private x86_64 ramdisk true
IMAGE ami-p3tdhsdm winxp2_bucket/win-boot2.img.manifest.xml IRT available private x86_64 ramdisk true
IMAGE ami-mcnbexg8 winxp2_bucket/memdisk.manifest.xml IRT available private x86_64 kernel true
IMAGE ami-tiny nebula/tiny vishvananda available public x86_64 machine aki-lucid ari-lucid
IMAGE ami-pysc0kg3 winxp_bucket/winxp.img.manifest.xml IRT available private x86_64 machine ami-qhvha7sg ami-r32sryaf
IMAGE ami-9bsb0d3n mybucket/kernel.manifest.xml IRT available private x86_64 kernel true
IMAGE ami-r32sryaf winxp_bucket/win-boot.img.manifest.xml IRT available private x86_64 ramdisk true
IMAGE aki-lucid nebula/lucid-kernel admin available public x86_64 kernel
IMAGE ari-lucid nebula/lucid-ramdisk admin available public x86_64 ramdisk
IMAGE ami-rv30h74o winxp2_bucket/winxp2.img.manifest.xml IRT available private x86_64 machine ami-mcnbexg8 ami-p3tdhsdm
==========================================================================
I am appending the part of the log (screenog.3 for compute) which I believe shows one of the problems:
===========================================================================================
OperationalError: (OperationalError) database is locked u'UPDATE services SET updated_at=?, report_count=? WHERE services.id = ?' ('2011-01-07 05:07:21.946395', 56, 3)
ERROR:root:Recovered model server connection!
DEBUG:root:instance 1: starting...
DEBUG:root:Running cmd (subprocess): ifconfig vlan100
DEBUG:root:Running cmd (subprocess): ifconfig br100
DEBUG:root:Running cmd (subprocess): sudo ifconfig br100 up
DEBUG:root:Running cmd (subprocess): sudo iptables --delete FORWARD --in-interface br100 -j ACCEPT
DEBUG:root:Running cmd (subprocess): sudo iptables -I FORWARD --in-interface br100 -j ACCEPT
DEBUG:root:Running cmd (subprocess): sudo iptables --delete FORWARD --out-interface br100 -j ACCEPT
DEBUG:root:Running cmd (subprocess): sudo iptables -I FORWARD --out-interface br100 -j ACCEPT
DEBUG:root:instance instance-1037839504: starting toXML method
DEBUG:root:instance instance-1037839504: finished toXML method
DEBUG:root:Running cmd (subprocess): mkdir -p /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/
DEBUG:root:Running cmd (subprocess): chmod 0777 /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/
INFO:root:instance instance-1037839504: Creating image
DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-rv30h74o/image -H "Date: Fri, 07 Jan 2011 05:10:38 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:AtMPl1lwqgV3sYhjA+lrnaWFelQ=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
ERROR:root:model server went away
Traceback (most recent call last):
  File "/home/alorenzo/nova/nova/nova/service.py", line 194, in report_state
    {'report_count': service_ref['report_count'] + 1})
  File "/home/alorenzo/nova/nova/nova/db/api.py", line 127, in service_update
    return IMPL.service_update(context, service_id, values)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 249, in service_update
    service_ref.save(session=session)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/models.py", line 57, in save
    session.flush()
  File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/session.py", line 1346, in flush
    self._flush(objects)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/session.py", line 1427, in _flush
    flush_context.execute()
  File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/unitofwork.py", line 299, in execute
    rec.execute(self)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/unitofwork.py", line 443, in execute
    uow
  File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/mapper.py", line 1800, in _save_obj
    execute(statement, params)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1157, in execute
    params)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1237, in _execute_clauseelement
    return self.__execute_context(context)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1268, in __execute_context
    context.parameters[0], context=context)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1367, in _cursor_execute
    context)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1360, in _cursor_execute
    context)
  File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/default.py", line 288, in do_execute
    cursor.execute(statement, parameters)
OperationalError: (OperationalError) database is locked u'UPDATE services SET updated_at=?, report_count=? WHERE services.id = ?' ('2011-01-07 05:10:54.973624', 74, 3)
DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-mcnbexg8/image -H "Date: Fri, 07 Jan 2011 05:11:07 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:mV/ZBfz5LMpCGWO0RGTSuKrZpnY=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/kernel
DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-p3tdhsdm/image -H "Date: Fri, 07 Jan 2011 05:11:15 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:6YPspxVt5cUCWyl6n9glNRe1EO0=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/ramdisk
INFO:root:instance instance-1037839504: injecting key into image ami-rv30h74o
DEBUG:root:Running cmd (subprocess): sudo losetup --find --show /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
DEBUG:root:Running cmd (subprocess): sudo tune2fs -c 0 -i 0 /dev/loop0
DEBUG:root:Result was 1
DEBUG:root:Running cmd (subprocess): sudo losetup --detach /dev/loop0
WARNING:root:instance instance-1037839504: ignoring error injecting data into image ami-rv30h74o (Unexpected error while running command.
Command: sudo tune2fs -c 0 -i 0 /dev/loop0
Exit code: 1
Stdout: 'tune2fs 1.41.11 (14-Mar-2010)\n'
Stderr: "tune2fs: Bad magic number in super-block while trying to open /dev/loop0\r\nCouldn't find valid filesystem superblock.\n")
DEBUG:root:Running cmd (subprocess): dd if=/dev/zero of=/home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw count=1 seek=20971519 bs=512
DEBUG:root:Running cmd (subprocess): e2fsck -fp /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
DEBUG:root:Result was 8
DEBUG:root:Running cmd (subprocess): resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
DEBUG:root:Result was 1
ERROR:root:Uncaught exception
Traceback (most recent call last):
  File "/home/alorenzo/nova/nova/nova/exception.py", line 83, in _wrap
    return f(*args, **kw)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 347, in spawn
    self._create_image(instance, xml)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 512, in _create_image
    local_bytes, resize, execute=execute)
  File "/home/alorenzo/nova/nova/nova/compute/disk.py", line 67, in partition
    execute('resize2fs %s' % infile)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 459, in execute
    check_exit_code=check_exit_code)
  File "/home/alorenzo/nova/nova/nova/utils.py", line 143, in execute
    cmd=cmd)
ProcessExecutionError: Unexpected error while running command.
Command: resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
Exit code: 1
Stdout: "Couldn't find valid filesystem superblock.\n"
Stderr: 'resize2fs 1.41.11 (14-Mar-2010)\nresize2fs: Bad magic number in super-block while trying to open /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw\r\n'
ERROR:root:instance instance-1037839504: Failed to spawn
Traceback (most recent call last):
  File "/home/alorenzo/nova/nova/nova/compute/manager.py", line 146, in run_instance
    self.driver.spawn(instance_ref)
  File "/home/alorenzo/nova/nova/nova/exception.py", line 89, in _wrap
    raise Error(str(e))
Error: Unexpected error while running command.
Command: resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
Exit code: 1
Stdout: "Couldn't find valid filesystem superblock.\n"
Stderr: 'resize2fs 1.41.11 (14-Mar-2010)\nresize2fs: Bad magic number in super-block while trying to open /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw\r\n'
libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
ERROR:root:Recovered model server connection!
DEBUG:root:Deallocating address 10.0.0.3
DEBUG:root:instance 1: terminating
libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
ERROR:root:in looping call
Traceback (most recent call last):
  File "/home/alorenzo/nova/nova/nova/utils.py", line 323, in _inner
    self.f(*self.args, **self.kw)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 196, in _wait_for_shutdown
    power_state.SHUTDOWN)
  File "/home/alorenzo/nova/nova/nova/db/api.py", line 363, in instance_set_state
    return IMPL.instance_set_state(context, instance_id, state, description)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 822, in instance_set_state
    'state_description': description})
  File "/home/alorenzo/nova/nova/nova/db/api.py", line 372, in instance_update
    return IMPL.instance_update(context, instance_id, values)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 829, in instance_update
    instance_ref = instance_get(context, instance_id, session=session)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 679, in instance_get
    raise exception.NotFound(_('No instance for id %s') % instance_id)
NotFound: No instance for id 1
Traceback (most recent call last):
  File "/usr/lib/pymodules/python2.6/eventlet/hubs/hub.py", line 321, in fire_timers
    timer()
  File "/usr/lib/pymodules/python2.6/eventlet/hubs/timer.py", line 56, in __call__
    cb(*args, **kw)
  File "/usr/lib/pymodules/python2.6/eventlet/greenthread.py", line 202, in main
    result = function(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 207, in _wait_for_timer
    timer_done.wait()
  File "/usr/lib/pymodules/python2.6/eventlet/event.py", line 120, in wait
    current.throw(*self._exc)
  File "/home/alorenzo/nova/nova/nova/utils.py", line 323, in _inner
    self.f(*self.args, **self.kw)
  File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 196, in _wait_for_shutdown
    power_state.SHUTDOWN)
  File "/home/alorenzo/nova/nova/nova/db/api.py", line 363, in instance_set_state
    return IMPL.instance_set_state(context, instance_id, state, description)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 822, in instance_set_state
    'state_description': description})
  File "/home/alorenzo/nova/nova/nova/db/api.py", line 372, in instance_update
    return IMPL.instance_update(context, instance_id, values)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 829, in instance_update
    instance_ref = instance_get(context, instance_id, session=session)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
    return f(*args, **kwargs)
  File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 679, in instance_get
    raise exception.NotFound(_('No instance for id %s') % instance_id)
NotFound: No instance for id 1

=============================================================================================

I do have all the other logs (api,network,etc) but there is no way to attach a txt file on this forum.

Thanks for the help

Question information

Language:
English Edit question
Status:
Solved
For:
OpenStack Compute (nova) Edit question
Assignee:
No assignee Edit question
Solved by:
Vish Ishaya
Solved:
Last query:
Last reply:
Revision history for this message
Best Vish Ishaya (vishvananda) said :
#1

The windows image needs virtio block drivers. I managed to get a windows instance to boot without them, but I had to modify the libvirt.xml to switch to ide drives instead.

Here is a basic walkthrough of what i did to get a windows image to boot:

Create a whole disk image version of windows.
create an ami-win folder in your images folder
copy your whole disk image to a file called ami-win/image
create an ami-win/info.json file with the following info:
{
    "imageId": "ami-win",
    "imageLocation": "nova/win",
    "imageOwnerId": "admin",
    "imageState": "available",
    "type": "machine",
    "isPublic": true,
    "architecture": "x86_64"
}
Run the windows instance
euca-run-instances -t m1.medium ami-tiny
(it will fail to run here unless you've installed virtio drivers in your image)

# there will be a dir in your instance directory: instance-xxxxx
cd instance-xxxxx
virsh destroy instance-xxxxx # use the number from the directory name in place of the xxx
# edit the libvirt.xml by replacing <target dev='vda' bus='virtio'/>
# with <target dev='hda' bus='ide'/>
# If your windows instance doesn't include some kind of terminal services, you can also add a vnc console in the devices section:
# <graphics type='vnc' port='5901' listen='<host_ip>' passwd='<password>'/>
# recreate the instance using virsh
virsh create libvirt.xml

a properly configured windows instance should have virtio drivers installed and have some kind of terminal services client running so it won't need these changes, but this should get you started in testing.

The final issue is how to get a password into the windows instance. Key injection obviously doesn't work. I think the easiest solution is to have a script that runs on the windows instance to query user data from the metadata server, the password can be sent in in userdata.

Vish

On Jan 10, 2011, at 8:13 AM, arturo lorenzo wrote:

> New question #140899 on OpenStack Compute (nova):
> https://answers.launchpad.net/nova/+question/140899
>
> Trying to start a windows instance under nova (ubuntu 10.4 lts 64bit) and get errors when launching the instance. The status goes from launching to pending and stays in pending forever. Tried all tricks to connect via VNC and to ignore kernel and ramdisk unsuccessfully. I can run linux instances successfully and also the same windows image can be run successfully via KVM.
> Created the image with euca tools provided by the nova stack and using this URL as a reference:
> http://kiranmurari.wordpress.com/2010/03/29/uec-bundling-windows-image/
> The first I've noticed was when registering the memdisk I did not get an EKI-xxxxxxx I got an AMI-xxxxxxx and the same with ramdisk.
> But I was able to continue and bundle the image successfully using the AMIs from kernel and ramdisk.
> Here is the list of my images:
> ==========================================================================
> root@ubuntu-svr2:/home/alorenzo/windowsxp# euca-describe-images
> IMAGE ami-ol70fuy6 mybucket/machine.manifest.xml IRT available private x86_64 machine ami-9bsb0d3n ami-8lw4w0x5
> IMAGE ami-qhvha7sg winxp_bucket/memdisk.manifest.xml IRT available private x86_64 kernel true
> IMAGE ami-8lw4w0x5 mybucket/ramdisk.manifest.xml IRT available private x86_64 ramdisk true
> IMAGE ami-p3tdhsdm winxp2_bucket/win-boot2.img.manifest.xml IRT available private x86_64 ramdisk true
> IMAGE ami-mcnbexg8 winxp2_bucket/memdisk.manifest.xml IRT available private x86_64 kernel true
> IMAGE ami-tiny nebula/tiny vishvananda available public x86_64 machine aki-lucid ari-lucid
> IMAGE ami-pysc0kg3 winxp_bucket/winxp.img.manifest.xml IRT available private x86_64 machine ami-qhvha7sg ami-r32sryaf
> IMAGE ami-9bsb0d3n mybucket/kernel.manifest.xml IRT available private x86_64 kernel true
> IMAGE ami-r32sryaf winxp_bucket/win-boot.img.manifest.xml IRT available private x86_64 ramdisk true
> IMAGE aki-lucid nebula/lucid-kernel admin available public x86_64 kernel
> IMAGE ari-lucid nebula/lucid-ramdisk admin available public x86_64 ramdisk
> IMAGE ami-rv30h74o winxp2_bucket/winxp2.img.manifest.xml IRT available private x86_64 machine ami-mcnbexg8 ami-p3tdhsdm
> ==========================================================================
> I am appending the part of the log (screenog.3 for compute) which I believe shows one of the problems:
> ===========================================================================================
> OperationalError: (OperationalError) database is locked u'UPDATE services SET updated_at=?, report_count=? WHERE services.id = ?' ('2011-01-07 05:07:21.946395', 56, 3)
> ERROR:root:Recovered model server connection!
> DEBUG:root:instance 1: starting...
> DEBUG:root:Running cmd (subprocess): ifconfig vlan100
> DEBUG:root:Running cmd (subprocess): ifconfig br100
> DEBUG:root:Running cmd (subprocess): sudo ifconfig br100 up
> DEBUG:root:Running cmd (subprocess): sudo iptables --delete FORWARD --in-interface br100 -j ACCEPT
> DEBUG:root:Running cmd (subprocess): sudo iptables -I FORWARD --in-interface br100 -j ACCEPT
> DEBUG:root:Running cmd (subprocess): sudo iptables --delete FORWARD --out-interface br100 -j ACCEPT
> DEBUG:root:Running cmd (subprocess): sudo iptables -I FORWARD --out-interface br100 -j ACCEPT
> DEBUG:root:instance instance-1037839504: starting toXML method
> DEBUG:root:instance instance-1037839504: finished toXML method
> DEBUG:root:Running cmd (subprocess): mkdir -p /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/
> DEBUG:root:Running cmd (subprocess): chmod 0777 /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/
> INFO:root:instance instance-1037839504: Creating image
> DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-rv30h74o/image -H "Date: Fri, 07 Jan 2011 05:10:38 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:AtMPl1lwqgV3sYhjA+lrnaWFelQ=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> ERROR:root:model server went away
> Traceback (most recent call last):
> File "/home/alorenzo/nova/nova/nova/service.py", line 194, in report_state
> {'report_count': service_ref['report_count'] + 1})
> File "/home/alorenzo/nova/nova/nova/db/api.py", line 127, in service_update
> return IMPL.service_update(context, service_id, values)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 249, in service_update
> service_ref.save(session=session)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/models.py", line 57, in save
> session.flush()
> File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/session.py", line 1346, in flush
> self._flush(objects)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/session.py", line 1427, in _flush
> flush_context.execute()
> File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/unitofwork.py", line 299, in execute
> rec.execute(self)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/unitofwork.py", line 443, in execute
> uow
> File "/usr/lib/pymodules/python2.6/sqlalchemy/orm/mapper.py", line 1800, in _save_obj
> execute(statement, params)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1157, in execute
> params)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1237, in _execute_clauseelement
> return self.__execute_context(context)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1268, in __execute_context
> context.parameters[0], context=context)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1367, in _cursor_execute
> context)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/base.py", line 1360, in _cursor_execute
> context)
> File "/usr/lib/pymodules/python2.6/sqlalchemy/engine/default.py", line 288, in do_execute
> cursor.execute(statement, parameters)
> OperationalError: (OperationalError) database is locked u'UPDATE services SET updated_at=?, report_count=? WHERE services.id = ?' ('2011-01-07 05:10:54.973624', 74, 3)
> DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-mcnbexg8/image -H "Date: Fri, 07 Jan 2011 05:11:07 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:mV/ZBfz5LMpCGWO0RGTSuKrZpnY=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/kernel
> DEBUG:root:Running cmd (subprocess): /usr/bin/curl --fail --silent http://192.168.1.194:3333/_images/ami-p3tdhsdm/image -H "Date: Fri, 07 Jan 2011 05:11:15 GMT" -H "Authorization: AWS cfc45e05-ec76-4c70-b4d8-0ce0119d1a07:IRT:6YPspxVt5cUCWyl6n9glNRe1EO0=" -o /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/ramdisk
> INFO:root:instance instance-1037839504: injecting key into image ami-rv30h74o
> DEBUG:root:Running cmd (subprocess): sudo losetup --find --show /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> DEBUG:root:Running cmd (subprocess): sudo tune2fs -c 0 -i 0 /dev/loop0
> DEBUG:root:Result was 1
> DEBUG:root:Running cmd (subprocess): sudo losetup --detach /dev/loop0
> WARNING:root:instance instance-1037839504: ignoring error injecting data into image ami-rv30h74o (Unexpected error while running command.
> Command: sudo tune2fs -c 0 -i 0 /dev/loop0
> Exit code: 1
> Stdout: 'tune2fs 1.41.11 (14-Mar-2010)\n'
> Stderr: "tune2fs: Bad magic number in super-block while trying to open /dev/loop0\r\nCouldn't find valid filesystem superblock.\n")
> DEBUG:root:Running cmd (subprocess): dd if=/dev/zero of=/home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw count=1 seek=20971519 bs=512
> DEBUG:root:Running cmd (subprocess): e2fsck -fp /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> DEBUG:root:Result was 8
> DEBUG:root:Running cmd (subprocess): resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> DEBUG:root:Result was 1
> ERROR:root:Uncaught exception
> Traceback (most recent call last):
> File "/home/alorenzo/nova/nova/nova/exception.py", line 83, in _wrap
> return f(*args, **kw)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 347, in spawn
> self._create_image(instance, xml)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 512, in _create_image
> local_bytes, resize, execute=execute)
> File "/home/alorenzo/nova/nova/nova/compute/disk.py", line 67, in partition
> execute('resize2fs %s' % infile)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 459, in execute
> check_exit_code=check_exit_code)
> File "/home/alorenzo/nova/nova/nova/utils.py", line 143, in execute
> cmd=cmd)
> ProcessExecutionError: Unexpected error while running command.
> Command: resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> Exit code: 1
> Stdout: "Couldn't find valid filesystem superblock.\n"
> Stderr: 'resize2fs 1.41.11 (14-Mar-2010)\nresize2fs: Bad magic number in super-block while trying to open /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw\r\n'
> ERROR:root:instance instance-1037839504: Failed to spawn
> Traceback (most recent call last):
> File "/home/alorenzo/nova/nova/nova/compute/manager.py", line 146, in run_instance
> self.driver.spawn(instance_ref)
> File "/home/alorenzo/nova/nova/nova/exception.py", line 89, in _wrap
> raise Error(str(e))
> Error: Unexpected error while running command.
> Command: resize2fs /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw
> Exit code: 1
> Stdout: "Couldn't find valid filesystem superblock.\n"
> Stderr: 'resize2fs 1.41.11 (14-Mar-2010)\nresize2fs: Bad magic number in super-block while trying to open /home/alorenzo/nova/nova/nova/..//instances/instance-1037839504/disk-raw\r\n'
> libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
> ERROR:root:Recovered model server connection!
> DEBUG:root:Deallocating address 10.0.0.3
> DEBUG:root:instance 1: terminating
> libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
> libvir: QEMU error : Domain not found: no domain with matching name 'instance-1037839504'
> ERROR:root:in looping call
> Traceback (most recent call last):
> File "/home/alorenzo/nova/nova/nova/utils.py", line 323, in _inner
> self.f(*self.args, **self.kw)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 196, in _wait_for_shutdown
> power_state.SHUTDOWN)
> File "/home/alorenzo/nova/nova/nova/db/api.py", line 363, in instance_set_state
> return IMPL.instance_set_state(context, instance_id, state, description)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 822, in instance_set_state
> 'state_description': description})
> File "/home/alorenzo/nova/nova/nova/db/api.py", line 372, in instance_update
> return IMPL.instance_update(context, instance_id, values)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 829, in instance_update
> instance_ref = instance_get(context, instance_id, session=session)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 679, in instance_get
> raise exception.NotFound(_('No instance for id %s') % instance_id)
> NotFound: No instance for id 1
> Traceback (most recent call last):
> File "/usr/lib/pymodules/python2.6/eventlet/hubs/hub.py", line 321, in fire_timers
> timer()
> File "/usr/lib/pymodules/python2.6/eventlet/hubs/timer.py", line 56, in __call__
> cb(*args, **kw)
> File "/usr/lib/pymodules/python2.6/eventlet/greenthread.py", line 202, in main
> result = function(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 207, in _wait_for_timer
> timer_done.wait()
> File "/usr/lib/pymodules/python2.6/eventlet/event.py", line 120, in wait
> current.throw(*self._exc)
> File "/home/alorenzo/nova/nova/nova/utils.py", line 323, in _inner
> self.f(*self.args, **self.kw)
> File "/home/alorenzo/nova/nova/nova/virt/libvirt_conn.py", line 196, in _wait_for_shutdown
> power_state.SHUTDOWN)
> File "/home/alorenzo/nova/nova/nova/db/api.py", line 363, in instance_set_state
> return IMPL.instance_set_state(context, instance_id, state, description)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 97, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 822, in instance_set_state
> 'state_description': description})
> File "/home/alorenzo/nova/nova/nova/db/api.py", line 372, in instance_update
> return IMPL.instance_update(context, instance_id, values)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 829, in instance_update
> instance_ref = instance_get(context, instance_id, session=session)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 108, in wrapper
> return f(*args, **kwargs)
> File "/home/alorenzo/nova/nova/nova/db/sqlalchemy/api.py", line 679, in instance_get
> raise exception.NotFound(_('No instance for id %s') % instance_id)
> NotFound: No instance for id 1
>
> =============================================================================================
>
> I do have all the other logs (api,network,etc) but there is no way to attach a txt file on this forum.
>
> Thanks for the help
>
>
> --
> You received this question notification because you are a member of Nova
> Core, which is an answer contact for OpenStack Compute (nova).

Revision history for this message
Jordan Rinke (jordanrinke) said :
#2

Just a note on the password part, I believe Ed Leafe sent out an RFC for a client to do exactly that but I am not certain / don't know the status.

Revision history for this message
arturo lorenzo (arturo-lorenzo) said :
#3

Thanks Vish Ishaya, that solved my question.