Note: This bug is displayed in read-only format because the product is no longer active in Red Hat Bugzilla.

Bug 1710040

Summary: [RHOS 15] Tempest test failure: test_live_block_migration
Product: Red Hat OpenStack Reporter: Archit Modi <amodi>
Component: openstack-novaAssignee: melanie witt <mwitt>
Status: CLOSED DUPLICATE QA Contact: OSP DFG:Compute <osp-dfg-compute>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 15.0 (Stein)CC: dasmith, eglynn, jhakimra, kchamart, mbooth, sbauza, sgordon, vromanso
Target Milestone: ---   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 1710041 (view as bug list) Environment:
Last Closed: 2019-05-24 14:25:52 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Attachments:
Description Flags
tempest-run.log none

Description Archit Modi 2019-05-14 19:33:33 UTC
Created attachment 1568624 [details]
tempest-run.log

Description of problem: Running the following tempest tests post RHOS-15 deployment, results in failure:

tempest.api.compute.admin.test_live_migration.LiveAutoBlockMigrationV225Test.test_live_block_migration	
tempest.api.compute.admin.test_live_migration.LiveMigrationTest.test_live_block_migration

core_puddle: RHOS_TRUNK-15.0-RHEL-8-20190509.n.1

How reproducible: always

Steps to Reproduce:
1. Deploy Rhos-15 with 1 controller, 2 compute topology
2. Run the following tempest tests:
tempest.api.compute.admin.test_live_migration.LiveAutoBlockMigrationV225Test.test_live_block_migration	
tempest.api.compute.admin.test_live_migration.LiveMigrationTest.test_live_block_migration
 

Actual results:
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:01 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-cedc0ac7-ef00-4d09-80ce-9cb3878e9592', 'x-compute-request-id': 'req-cedc0ac7-ef00-4d09-80ce-9cb3878e9592', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:03,227 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.604s
2019-05-11 21:19:03,228 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:02 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-3e1d948f-306d-4914-80b8-3313ae240a54', 'x-compute-request-id': 'req-3e1d948f-306d-4914-80b8-3313ae240a54', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:04,692 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.457s
2019-05-11 21:19:04,692 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:04 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-825d4bb3-7da5-430b-9f37-8563897f06c9', 'x-compute-request-id': 'req-825d4bb3-7da5-430b-9f37-8563897f06c9', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:06,303 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.604s
2019-05-11 21:19:06,303 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:05 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-0a2a1aa9-198b-4db6-a0cd-89234c17b029', 'x-compute-request-id': 'req-0a2a1aa9-198b-4db6-a0cd-89234c17b029', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:07,986 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.676s
2019-05-11 21:19:07,987 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:07 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-d4ee4433-4649-4453-b9d6-c8085c56c803', 'x-compute-request-id': 'req-d4ee4433-4649-4453-b9d6-c8085c56c803', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:09,965 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.972s
2019-05-11 21:19:09,966 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:08 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-55d97419-0fdd-4b39-8c18-2a1d96913613', 'x-compute-request-id': 'req-55d97419-0fdd-4b39-8c18-2a1d96913613', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:11,363 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.390s
2019-05-11 21:19:11,363 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:10 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-d576d20f-e046-4857-8ab7-48a51ead4e03', 'x-compute-request-id': 'req-d576d20f-e046-4857-8ab7-48a51ead4e03', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:12,894 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.524s
2019-05-11 21:19:12,894 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:12 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-7b748488-51bc-42ff-a528-632226fb1922', 'x-compute-request-id': 'req-7b748488-51bc-42ff-a528-632226fb1922', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:14,327 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.426s
2019-05-11 21:19:14,327 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:13 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-e62a2e26-43ec-4a3c-9a4e-6743a6ab5340', 'x-compute-request-id': 'req-e62a2e26-43ec-4a3c-9a4e-6743a6ab5340', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
2019-05-11 21:19:16,006 215818 INFO     [tempest.lib.common.rest_client] Request (LiveAutoBlockMigrationV225Test:test_live_block_migration): 200 GET http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683 0.672s
2019-05-11 21:19:16,007 215818 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-OpenStack-Nova-API-Version': '2.25', 'X-Auth-Token': '<omitted>'}
        Body: None
    Response - Headers: {'date': 'Sat, 11 May 2019 21:19:15 GMT', 'server': 'Apache', 'content-length': '1613', 'openstack-api-version': 'compute 2.25', 'x-openstack-nova-api-version': '2.25', 'vary': 'OpenStack-API-Version,X-OpenStack-Nova-API-Version,Accept-Encoding', 'x-openstack-request-id': 'req-899897f3-dc64-4cd2-ba4a-7eb894bfac45', 'x-compute-request-id': 'req-899897f3-dc64-4cd2-ba4a-7eb894bfac45', 'connection': 'close', 'content-type': 'application/json', 'status': '200', 'content-location': 'http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683'}
        Body: b'{"server": {"id": "10097d4c-3bfd-4fd8-8d22-ec322887b683", "name": "tempest-LiveAutoBlockMigrationV225Test-server-1885017972", "status": "MIGRATING", "tenant_id": "d6fb31a901cd4ce5beadf4f25078c32f", "user_id": "86dbc929ef1140ca93f3c6bb9479ad67", "metadata": {}, "hostId": "580e225c71ee45bdcfa1bf7bee0bcd80b23fb697c9ee2308cf590db8", "image": {"id": "0e69750d-552c-4f0e-a9d6-797bc415aa7f", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/images/0e69750d-552c-4f0e-a9d6-797bc415aa7f"}]}, "flavor": {"id": "ac93de77-01ce-4e91-a172-71875c18c55e", "links": [{"rel": "bookmark", "href": "http://10.0.0.137:8774/flavors/ac93de77-01ce-4e91-a172-71875c18c55e"}]}, "created": "2019-05-11T21:13:08Z", "updated": "2019-05-11T21:14:08Z", "addresses": {"tempest-LiveAutoBlockMigrationV225Test-124331306-network": [{"version": 4, "addr": "10.100.0.9", "OS-EXT-IPS:type": "fixed", "OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:03:88:8d"}]}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "http://10.0.0.137:8774/v2.1/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}, {"rel": "bookmark", "href": "http://10.0.0.137:8774/servers/10097d4c-3bfd-4fd8-8d22-ec322887b683"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "key_name": null, "OS-SRV-USG:launched_at": "2019-05-11T21:14:04.000000", "OS-SRV-USG:terminated_at": null, "security_groups": [{"name": "default"}], "OS-EXT-STS:task_state": "migrating", "OS-EXT-STS:vm_state": "active", "OS-EXT-STS:power_state": 1, "os-extended-volumes:volumes_attached": [], "locked": false, "description": null}}'
}}}

Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/tempest/api/compute/admin/test_live_migration.py", line 129, in test_live_block_migration
    self._test_live_migration()
  File "/usr/lib/python3.6/site-packages/tempest/api/compute/admin/test_live_migration.py", line 119, in _test_live_migration
    self._live_migrate(server_id, destination_host, state, volume_backed)
  File "/usr/lib/python3.6/site-packages/tempest/api/compute/admin/test_live_migration.py", line 77, in _live_migrate
    waiters.wait_for_server_status(self.servers_client, server_id, state)
  File "/usr/lib/python3.6/site-packages/tempest/common/waiters.py", line 96, in wait_for_server_status
    raise lib_exc.TimeoutException(message)
tempest.lib.exceptions.TimeoutException: Request timed out
Details: (LiveAutoBlockMigrationV225Test:test_live_block_migration) Server 10097d4c-3bfd-4fd8-8d22-ec322887b683 failed to reach ACTIVE status and task state "None" within the required time (300 s). Current status: MIGRATING. Current task state: migrating.

Comment 3 melanie witt 2019-05-17 14:18:35 UTC
For tempest.api.compute.admin.test_live_migration.LiveAutoBlockMigrationV225Test.test_live_block_migration, we see in the nova-compute.log:

2019-05-11 21:19:07.526 8 DEBUG nova.compute.resource_tracker [req-54455960-5c54-4da8-9e43-3aa6d6b40bbd - - - - -] Migration for instance 10097d4c-3bfd-4fd8-8d22-ec322887b683 refers to another host's instance! _pair_instances_to_migrations /usr/lib/python3.6/site-packages/nova/compute/resource_tracker.py:759

2019-05-11 21:19:07.673 8 WARNING nova.compute.resource_tracker [req-54455960-5c54-4da8-9e43-3aa6d6b40bbd - - - - -] Instance 10097d4c-3bfd-4fd8-8d22-ec322887b683 has been moved to another host compute-1.localdomain(compute-1.localdomain). There are allocations remaining against the source host that might need to be removed: {'resources': {'DISK_GB': 1, 'MEMORY_MB': 64, 'VCPU': 1}}.

[1] http://cougar11.scl.lab.tlv.redhat.com/DFG-compute-nova-15_director-rhel-virthost-1cont_2comp-ipv4-geneve-live-migration/6/compute-0.tar.gz?compute-0/var/log/containers/nova/nova-compute.log

Comment 4 melanie witt 2019-05-17 15:16:04 UTC
For tempest.api.compute.admin.test_live_migration.LiveMigrationTest.test_live_block_migration, we see in nova-compute.log:

2019-05-11 21:20:38.051 8 WARNING nova.compute.manager [-] [instance: 8f004f1e-f0d5-438a-8386-40af86c9ec4c] Timed out waiting for events: [('network-vif-plugged', '9b7746a4-a6e7-438d-a394-f91dd8cc2fed')]. If these timeouts are a persistent issue it could mean the networking backend on host compute-1.localdomain does not support sending these events unless there are port binding host changes which does not happen at this point in the live migration process. You may need to disable the live_migration_wait_for_vif_plug option on host compute-1.localdomain.: eventlet.timeout.Timeout: 300 seconds

which shows a timeout waiting for the 'network-vif-plugged' event from neutron.

2019-05-11 21:20:38.141 8 INFO nova.compute.manager [-] [instance: 8f004f1e-f0d5-438a-8386-40af86c9ec4c] Swapping old allocation on 893f3bb8-7710-44a3-bc26-012617217beb held by migration be728c3b-5e43-4e09-965f-e9b4e11abdfa for instance
2019-05-11 21:20:38.232 8 DEBUG nova.scheduler.client.report [-] Overwriting current allocation {'allocations': {'e8eb707f-90d3-4046-8053-a25f468b1269': {'resources': {'DISK_GB': 1, 'MEMORY_MB': 64, 'VCPU': 1}, 'generation': 143}}, 'project_id': '646583384be54cfda42f90b6d2dee13a', 'user_id': '181b6610a53b405a96c5c0fca7bba6fb', 'consumer_generation': 1} on consumer 8f004f1e-f0d5-438a-8386-40af86c9ec4c move_allocations /usr/lib/python3.6/site-packages/nova/scheduler/client/report.py:1883

These ^ log messages are showing the revert of placement allocations, presumably during _rollback_live_migration.

2019-05-11 21:20:45.703 8 DEBUG nova.compute.manager [req-4d54bad3-6491-42d5-a95b-a686e9655791 b2444e993ea64c0fb247340e24e26004 eaf60d0147884211a27211f29f21fc31 - default default] [instance: 8f004f1e-f0d5-438a-8386-40af86c9ec4c] Received event network-changed-9b7746a4-a6e7-438d-a394-f91dd8cc2fed external_instance_event /usr/lib/python3.6/site-packages/nova/compute/manager.py:8357

2019-05-11 21:26:12.377 8 DEBUG nova.compute.manager [req-9fbdc30d-7a73-4bc3-b254-6a727e230746 181b6610a53b405a96c5c0fca7bba6fb 646583384be54cfda42f90b6d2dee13a - default default] [instance: 8f004f1e-f0d5-438a-8386-40af86c9ec4c] Events pending at deletion: network-vif-plugged-9b7746a4-a6e7-438d-a394-f91dd8cc2fed _delete_instance /usr/lib/python3.6/site-packages/nova/compute/manager.py:2723

Comment 5 Matthew Booth 2019-05-24 14:25:52 UTC

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