Comment 5 for bug 1183283

Revision history for this message
Kashyap Chamarthy (kashyapc) wrote : Re: On a fresh devstack install, creating a new cinder volume fails with "Logical volume" exists.

Ok, I could consistently reproduce on F19, w/ latest devstack, at least.

This is how I gave a re-try. Am I still doing something incorrect here ?

# Update all git repositories
$ cd /opt/stack/ ; for i in `echo cinder glance nova horizon keystone noVNC \
pbr python-cinderclient python-glanceclient python-keystoneclient python-novaclient \
python-openstackclient \python-quantumclient python-swiftclient tempest`; \
do cd $i; git pull; cd ..; done

# Add a patch that lets Fedora-19+ systems can have devstack successfully configured
$ git fetch https://review.openstack.org/openstack-dev/devstack refs/changes/84/29784/1 && git checkout FETCH_HEAD

# Cleanup; fresh setup
$ ./unstack.sh && ./stack.sh
[...]
2013-05-24 04:04:16 + set +o xtrace
2013-05-24 04:04:16 stack.sh completed in 148 seconds.

# List the images
$ glance image-list
+--------------------------------------+---------------------------------+-------------+------------------+-----------+--------+
| ID | Name | Disk Format | Container Format | Size | Status |
+--------------------------------------+---------------------------------+-------------+------------------+-----------+--------+
| a5dc830a-2a9f-410c-a435-b49e7fb4abc6 | cirros-0.3.0-x86_64-uec | ami | ami | 25165824 | active |
| af6cc743-d24e-4e16-85c3-bf02221d14bd | cirros-0.3.0-x86_64-uec-kernel | aki | aki | 4731440 | active |
| 156f93d0-1808-4f72-9401-0cd43679f467 | cirros-0.3.0-x86_64-uec-ramdisk | ari | ari | 2254249 | active |
| 9d517cee-92d0-4975-8346-2bd2fd4ecf47 | f17-x86_64-openstack-sda | qcow2 | bare | 251985920 | active |
| a20e86fd-08ce-4245-b01b-0be23540de3f | Fedora18-Cloud-x86_64-latest | qcow2 | bare | 227409920 | active |
+--------------------------------------+---------------------------------+-------------+------------------+-----------+--------+

# Create a volume
$ cinder create --display-name image_vol-test-n1 --image_id 9d517cee-92d0-4975-8346-2bd2fd4ecf47 1
+---------------------+--------------------------------------+
| Property | Value |
+---------------------+--------------------------------------+
| attachments | [] |
| availability_zone | nova |
| bootable | false |
| created_at | 2013-05-24T08:05:36.135398 |
| display_description | None |
| display_name | image_vol-test-n1 |
| id | 94ac47f5-71d8-4adf-aa15-cbd758ed2b7f |
| image_id | 9d517cee-92d0-4975-8346-2bd2fd4ecf47 |
| metadata | {} |
| size | 1 |
| snapshot_id | None |
| source_volid | None |
| status | creating |
| volume_type | None |
+---------------------+--------------------------------------+

# List them
$ cinder list
+--------------------------------------+--------+-------------------+------+-------------+----------+-------------+
| ID | Status | Display Name | Size | Volume Type | Bootable | Attached to |
+--------------------------------------+--------+-------------------+------+-------------+----------+-------------+
| 94ac47f5-71d8-4adf-aa15-cbd758ed2b7f | error | image_vol-test-n1 | 1 | None | false | |
| c2094b47-f60f-481b-b49d-46e7ebe7c15c | error | image_vol-test-n2 | 1 | None | false | |
+--------------------------------------+--------+-------------------+------+-------------+----------+-------------+

Log Info:
=========
From less -R /home/kashyap/src/devstack-2-logs/data/logs/screen-c-vol.2013-05-24-040145.log

[...]
2013-05-24 04:05:39.481 ERROR cinder.volume.driver [req-fd01bf63-f2ef-4d6c-8974-7383d1f6044e 2dbbf38c7ac344c99c4b2a5813733454 273e1a6b14db4490a9a87e0e6584fd13] Recovering from a failed execute. Try number 1
2013-05-24 04:05:39.481 TRACE cinder.volume.driver Traceback (most recent call last):
2013-05-24 04:05:39.481 TRACE cinder.volume.driver File "/opt/stack/cinder/cinder/volume/driver.py", line 88, in _try_execute
2013-05-24 04:05:39.481 TRACE cinder.volume.driver self._execute(*command, **kwargs)
2013-05-24 04:05:39.481 TRACE cinder.volume.driver File "/opt/stack/cinder/cinder/utils.py", line 193, in execute
2013-05-24 04:05:39.481 TRACE cinder.volume.driver cmd=' '.join(cmd))
2013-05-24 04:05:39.481 TRACE cinder.volume.driver ProcessExecutionError: Unexpected error while running command.
2013-05-24 04:05:39.481 TRACE cinder.volume.driver Command: sudo cinder-rootwrap /etc/cinder/rootwrap.conf lvcreate -L 1G -n volume-94ac47f5-71d8-4adf-aa15-cbd758ed2b7f stack-volumes
2013-05-24 04:05:39.481 TRACE cinder.volume.driver Exit code: 5
2013-05-24 04:05:39.481 TRACE cinder.volume.driver Stdout: ''
2013-05-24 04:05:39.481 TRACE cinder.volume.driver Stderr: ' Logical volume "volume-94ac47f5-71d8-4adf-aa15-cbd758ed2b7f" already exists in volume group "stack-volumes"\n'
2013-05-24 04:05:39.481 TRACE cinder.volume.driver
2013-05-24 04:05:40.484 DEBUG cinder.utils [req-fd01bf63-f2ef-4d6c-8974-7383d1f6044e 2dbbf38c7ac344c99c4b2a5813733454 273e1a6b14db4490a9a87e0e6584fd13] Running cmd (subprocess): sudo cinder-rootwrap /etc/cinder/rootwrap.conf lvcreate -L 1G -n volume-94ac47f5-71d8-4adf-aa15-cbd758ed2b7f stack-volumes from (pid=29275) execute /opt/stack/cinder/cinder/utils.py:169
2013-05-24 04:05:40.674 DEBUG cinder.utils [req-fd01bf63-f2ef-4d6c-8974-7383d1f6044e 2dbbf38c7ac344c99c4b2a5813733454 273e1a6b14db4490a9a87e0e6584fd13] Result was 5 from (pid=29275) execute /opt/stack/cinder/cinder/utils.py:186
2013-05-24 04:05:40.675 ERROR cinder.volume.driver [req-fd01bf63-f2ef-4d6c-8974-7383d1f6044e 2dbbf38c7ac344c99c4b2a5813733454 273e1a6b14db4490a9a87e0e6584fd13] Recovering from a failed execute. Try number 2
2013-05-24 04:05:40.675 TRACE cinder.volume.driver Traceback (most recent call last):
2013-05-24 04:05:40.675 TRACE cinder.volume.driver File "/opt/stack/cinder/cinder/volume/driver.py", line 88, in _try_execute
2013-05-24 04:05:40.675 TRACE cinder.volume.driver self._execute(*command, **kwargs)
2013-05-24 04:05:40.675 TRACE cinder.volume.driver File "/opt/stack/cinder/cinder/utils.py", line 193, in execute
2013-05-24 04:05:40.675 TRACE cinder.volume.driver cmd=' '.join(cmd))
2013-05-24 04:05:40.675 TRACE cinder.volume.driver ProcessExecutionError: Unexpected error while running command.
2013-05-24 04:05:40.675 TRACE cinder.volume.driver Command: sudo cinder-rootwrap /etc/cinder/rootwrap.conf lvcreate -L 1G -n volume-94ac47f5-71d8-4adf-aa15-cbd758ed2b7f stack-volumes
2013-05-24 04:05:40.675 TRACE cinder.volume.driver Exit code: 5
2013-05-24 04:05:40.675 TRACE cinder.volume.driver Stdout: ''
2013-05-24 04:05:40.675 TRACE cinder.volume.driver Stderr: ' Logical volume "volume-94ac47f5-71d8-4adf-aa15-cbd758ed2b7f" already exists in volume group "stack-volumes"\n'
[...]

Other info:
===========
SELinux details.

$ getenforce
Permissive

$ cat /var/log/audit/audit.log | audit2allow -R

$