Bug 1281386

This test is skipped in tempest due to upstream bug https://bugs.launchpad.net/tempest/+bug/1205344
bug was reported since  : 2013-07-26

In order to run it , need to enable "validate" in tempest.conf,
Means we will  need ssh access to instances in tempest.

this test is skipped in upstream code  , so it will be skipped any way till bug solved.

  @decorators.skip_because(bug="1205344")
    @test.idempotent_id('10fd234a-515c-41e5-b092-8323060598c5')
    @testtools.skipUnless(CONF.compute_feature_enabled.snapshot,
                          'Snapshotting is not available.')
    @tempest.test.services('compute', 'network', 'volume', 'image')
    def test_stamp_pattern(self):


Once upstream but will be solved we will be able to run it.

*** This bug has been marked as a duplicate of bug 1205344 ***

Hi ,  
I managed to reproduce this issue locally with tempest .

Here are the steps :
#1 Create a volume 1G size
#2 Create an instance from image 
#3 attach the volume to the instance immediatly after server is up .
Nova triggers attach request to cinder.


From nova.api:
2015-11-18 06:58:04.631  get attach request


2015-11-18 06:58:04.631 3734 INFO nova.api.openstack.compute.contrib.volumes [req-fad984bb-317e-4ae3-b723-4554d0a7a318 72fe1c96402a48dc9e9279c281580021 8f0b9aaa1c084e8e94a27a3de81a5f73 - - -] Attach volume 2b7ae21e-4528-46eb-af21-a821b90aa6c1 to instance cbd09d10-d629-4d56-b15b-12b33a340ada at /dev/vdb
2015-11-18 06:58:05.133 3734 INFO nova.osapi_compute.wsgi.server [req-fad984bb-317e-4ae3-b723-4554d0a7a318 72fe1c96402a48dc9e9279c281580021 8f0b9aaa1c084e8e94a27a3de81a5f73 - - -] 192.168.100.1 "POST /v2/8f0b9aaa1c084e8e94a27a3de81a5f73/servers/cbd09d10-d629-4d56-b15b-12b33a340ada/os-volume_attachments HTTP/1.1" status: 200 len: 386 time: 0.5057290

From nova.compute logs :
2015-11-18 06:58:04.808 - attach request
2015-11-18 06:58:08.259 2334 - volume not found

2015-11-18 06:58:02.459 2334 INFO nova.compute.manager [req-e15172cf-5414-46cd-a187-984fc84b3fdc - - - - -] [instance: cbd09d10-d629-4d56-b15b-12b33a340ada] During sync_power_state the instance has a pending task (spawning). Skip.
2015-11-18 06:58:02.460 2334 INFO nova.compute.manager [req-e15172cf-5414-46cd-a187-984fc84b3fdc - - - - -] [instance: cbd09d10-d629-4d56-b15b-12b33a340ada] VM Resumed (Lifecycle Event)
2015-11-18 06:58:02.581 2334 INFO nova.compute.manager [-] [instance: cbd09d10-d629-4d56-b15b-12b33a340ada] During sync_power_state the instance has a pending task (spawning). Skip.
2015-11-18 06:58:02.770 2334 INFO nova.compute.resource_tracker [req-09ce08b2-1862-4ca6-b0e3-89e70afb5898 - - - - -] Auditing locally available compute resources for node compute1
2015-11-18 06:58:03.080 2334 INFO nova.compute.resource_tracker [req-09ce08b2-1862-4ca6-b0e3-89e70afb5898 - - - - -] Total usable vcpus: 2, total allocated vcpus: 1
2015-11-18 06:58:03.081 2334 INFO nova.compute.resource_tracker [req-09ce08b2-1862-4ca6-b0e3-89e70afb5898 - - - - -] Final resource view: name=compute1 phys_ram=3791MB used_ram=1024MB phys_disk=6GB used_disk=1GB total_vcpus=2 used_vcpus=1 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x5063e10>
2015-11-18 06:58:03.104 2334 INFO nova.scheduler.client.report [req-09ce08b2-1862-4ca6-b0e3-89e70afb5898 - - - - -] Compute_service record updated for ('compute1', 'compute1')
2015-11-18 06:58:03.104 2334 INFO nova.compute.resource_tracker [req-09ce08b2-1862-4ca6-b0e3-89e70afb5898 - - - - -] Compute_service record updated for compute1:compute1
2015-11-18 06:58:04.808 2334 INFO nova.compute.manager [req-fad984bb-317e-4ae3-b723-4554d0a7a318 72fe1c96402a48dc9e9279c281580021 8f0b9aaa1c084e8e94a27a3de81a5f73 - - -] [instance: cbd09d10-d629-4d56-b15b-12b33a340ada] Attaching volume 2b7ae21e-4528-46eb-af21-a821b90aa6c1 to /dev/vdb
2015-11-18 06:58:08.259 2334 WARNING nova.virt.libvirt.volume [req-fad984bb-317e-4ae3-b723-4554d0a7a318 72fe1c96402a48dc9e9279c281580021 8f0b9aaa1c084e8e94a27a3de81a5f73 - - -] ISCSI volume not yet found at: vdb. Will rescan & retry.  Try number: 0


from cinder logs:

2015-11-18 06:58:10.033 4135 INFO cinder.api.v1.volumes [req-bfc29a66-9356-45e6-9cc6-bb7b7a15de99 72fe1c96402a48dc9e9279c281580021 8f0b9aaa1c084e8e94a27a3de81a5f73 - - -] vol={'migration_status': None, 'provider_id': None, 'availability_zone': u'nova', 'terminated_at': None, 'updated_at': datetime.datetime(2015, 11, 18, 11, 58, 9), 'provider_geometry': None, 'replication_extended_status': None, 'replication_status': u'disabled', 'snapshot_id': None, 'ec2_id': None, 'deleted_at': None, 'id': u'2b7ae21e-4528-46eb-af21-a821b90aa6c1', 'size': 1L, 'user_id': u'72fe1c96402a48dc9e9279c281580021', 'volume_attachment': u'[<cinder.db.sqlalchemy.models.VolumeAttachment object at 0x611df50>]', 'display_description': None, 'volume_admin_metadata': u'[<cinder.db.sqlalchemy.models.VolumeAdminMetadata object at 0x611dd90>, <cinder.db.sqlalchemy.models.VolumeAdminMetadata object at 0x59e39d0>]', 'project_id': u'8f0b9aaa1c084e8e94a27a3de81a5f73', 'launched_at': datetime.datetime(2015, 11, 18, 11, 57, 41), 'scheduled_at': datetime.datetime(2015, 11, 18, 11, 57, 40), 'status': u'in-use', 'volume_type_id': None, 'multiattach': False, 'deleted': False, 'provider_location': u'192.168.101.193:3260,iqn.2010-10.org.openstack:volume-2b7ae21e-4528-46eb-af21-a821b90aa6c1 iqn.2010-10.org.openstack:volume-2b7ae21e-4528-46eb-af21-a821b90aa6c1 0', 'host': u'controller1@lvm1#lvm1', 'consistencygroup_id': None, 'source_volid': None, 'provider_auth': u'CHAP 2KkzGFBKsCAryaN5PNTV LAqD5jCt5wtYbyG3', 'display_name': u'tempest-TestStampPattern-1838133173', 'bootable': False, 'created_at': datetime.datetime(2015, 11, 18, 11, 57, 40), 'attach_status': u'attached', 'volume_type': None, 'consistencygroup': None, 'volume_metadata': [], '_name_id': None, 'encryption_key_id': None, 'replication_driver_data': None, 'metadata': {u'readonly': u'False', u'attached_mode': u'rw'}}