yahoo-eng-team team mailing list archive

Thread
Date
[Bug 1686540] [NEW] test_create_server_invalid_bdm_in_2nd_dict Failed

To: yahoo-eng-team@xxxxxxxxxxxxxxxxxxx
From: Saravana Ganeshan <1686540@xxxxxxxxxxxxxxxxxx>
Date: Wed, 26 Apr 2017 22:26:27 -0000
Reply-to: Bug 1686540 <1686540@xxxxxxxxxxxxxxxxxx>
Sender: bounces@xxxxxxxxxxxxx
Public bug reported:

When I run the test case test_create_server_invalid_bdm_in_2nd_dict in
tempest - the test case failed with the below result.

Openstack Version: Newton

==============================
Failed 1 tests - output below:
==============================

tempest.api.compute.servers.test_servers_negative.ServersNegativeTestJSON.test_create_server_invalid_bdm_in_2nd_dict[id-12146ac1-d7df-4928-ad25-b1f99e5286cd,negative]
----------------------------------------------------------------------------------------------------------------------------------------------------------------------

Captured traceback:
~~~~~~~~~~~~~~~~~~~
    Traceback (most recent call last):
      File "tempest/test.py", line 163, in wrapper
        raise exc
    tempest.lib.exceptions.ServerFault: Got server fault
    Details: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
    <type 'exceptions.ValueError'>


Captured pythonlogging:
~~~~~~~~~~~~~~~~~~~~~~~
    2017-04-26 14:57:42,265 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:setUp): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5 0.138s
    2017-04-26 14:57:42,266 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: None
        Response - Headers: {'status': '200', u'content-length': '1676', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5', u'date': 'Wed, 26 Apr 2017 21:57:42 GMT', u'x-compute-request-id': 'req-b24ea609-e7bb-4806-8679-98c32d75a780', u'content-type': 'application/json', u'connection': 'close'}
            Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"rally_verify_3842fe6d_39ORN5Gi": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:30:8d:2a", "version": 4, "addr": "10.2.0.4", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "self"}, {"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "bookmark"}], "image": {"id": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/images/2897cc0b-1d3c-40b9-8587-447b8d3e0445";, "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-04-26T21:57:41.000000", "flavor": {"id": "c742038a-4d78-4899-aa5f-269f502c8665", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/flavors/c742038a-4d78-4899-aa5f-269f502c8665";, "rel": "bookmark"}]}, "id": "6239b0ff-6900-4af8-8e49-5c4e0199afa5", "security_groups": [{"name": "default"}], "user_id": "5104ec988e964669997b4f8a80914288", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {}, "status": "ACTIVE", "updated": "2017-04-26T21:57:41Z", "hostId": "e49a0d4ef3c82f4d32305bbfd64ae2131f549992e45b4c1cb9be0de7", "OS-SRV-USG:terminated_at": null, "key_name": null, "name": "tempest-ServersNegativeTestJSON-server-1443765423", "created": "2017-04-26T21:57:35Z", "tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "os-extended-volumes:volumes_attached": [], "config_drive": ""}}
    2017-04-26 14:57:42,891 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 POST http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes 0.620s
    2017-04-26 14:57:42,892 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: {"volume": {"display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "size": 1}}
        Response - Headers: {'status': '200', u'content-length': '426', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes', u'x-compute-request-id': 'req-e4dcf42c-7c6c-4339-807a-a12de5627b28', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:42 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-e4dcf42c-7c6c-4339-807a-a12de5627b28'}
            Body: {"volume": {"status": "creating", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.726580", "multiattach": "false", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
    2017-04-26 14:57:43,308 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06 0.415s
    2017-04-26 14:57:43,308 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: None
        Response - Headers: {'status': '200', u'content-length': '494', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06', u'x-compute-request-id': 'req-f652a06e-7b6d-44df-ab25-6001c24ad2a3', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:43 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-f652a06e-7b6d-44df-ab25-6001c24ad2a3'}
            Body: {"volume": {"status": "creating", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.000000", "multiattach": "false", "os-vol-tenant-attr:tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
    2017-04-26 14:57:44,690 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06 0.380s
    2017-04-26 14:57:44,690 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: None
        Response - Headers: {'status': '200', u'content-length': '495', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06', u'x-compute-request-id': 'req-9bcda74c-d77b-48f6-b899-71ce714fad37', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:44 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-9bcda74c-d77b-48f6-b899-71ce714fad37'}
            Body: {"volume": {"status": "available", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.000000", "multiattach": "false", "os-vol-tenant-attr:tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
    2017-04-26 14:57:44,794 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 POST http://172.26.232.170:5000/v2.0/tokens
    2017-04-26 14:57:44,794 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json'}
            Body: <omitted>
        Response - Headers: {'status': '200', u'content-length': '3687', 'content-location': 'http://172.26.232.170:5000/v2.0/tokens', u'vary': 'X-Auth-Token', u'server': 'Apache', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:44 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-232fc162-b53d-4a9f-be3d-54fd215f5f2a'}
            Body: {"access": {"token": {"issued_at": "2017-04-26T21:57:44.000000Z", "expires": "2017-04-26T22:57:44Z", "id": "gAAAAABZARfY_lY-W0n7FGdJBvIOQDfNl8AKbsr--ehohO_s3OuNjfdlDeZWuQnhNlYmcjVpTFqDorpngtLW_-meOxierx0yVHOTDr6MxYHIXOspGhtqeoTEtkLlaE6DhR1iPOMLvjtPaUzrzJ39GhzVEpxV4-QVxsiaENHndIuV8omsecZMmQg", "tenant": {"description": "tempest-ServersNegativeTestJSON-693708565-desc", "enabled": true, "id": "84fddff4dcc44eecbfa6e8dc824e291d", "name": "tempest-ServersNegativeTestJSON-693708565"}, "audit_ids": ["bsX5Z8vtSQeRxv-Yzm3v0w"]}, "serviceCatalog": [{"endpoints": [{"adminURL": "http://20.0.0.10:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "557c508ff70f4a23a14842399512e1b8", "publicURL": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "compute", "name": "nova"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9696";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9696";, "id": "15f959f3f19647af8afff2ff77d693ae", "publicURL": "http://172.26.232.170:9696"}], "endpoints_links": [], "type": "network", "name": "neutron"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "4f0a76c7f4c446e795e651eab6a12b5c", "publicURL": "http://172.26.232.170:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "volumev2", "name": "cinderv2"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9292";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9292";, "id": "283a159622f0489da137a3bdb783a3ed", "publicURL": "http://172.26.232.170:9292"}], "endpoints_links": [], "type": "image", "name": "glance"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8000/v1";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8000/v1";, "id": "aea90d48891843e298eb0753f896d41f", "publicURL": "http://172.26.232.170:8000/v1"}], "endpoints_links": [], "type": "cloud-formation", "name": "heat-cfn"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "127c5f41e392456c85ee979fd41aa4d7", "publicURL": "http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "volume", "name": "cinder"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9999";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9999";, "id": "8dc14d561fa94e3594046a65406d5c00", "publicURL": "http://172.26.232.170:9999"}], "endpoints_links": [], "type": "health", "name": "cloudpulse"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "0dac27ac914148a0977b05eefc73a1be", "publicURL": "http://172.26.232.170:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "orchestration", "name": "heat"}, {"endpoints": [{"adminURL": "http://20.0.0.10:35357/v2.0";, "region": "RegionOne", "internalURL": "http://20.0.0.10:5000/v2.0";, "id": "8e4d3a59a68a41699cedf88776077c72", "publicURL": "http://172.26.232.170:5000/v2.0"}], "endpoints_links": [], "type": "identity", "name": "keystone"}], "user": {"username": "tempest-ServersNegativeTestJSON-693708565", "roles_links": [], "id": "5104ec988e964669997b4f8a80914288", "roles": [{"name": "_member_"}], "name": "tempest-ServersNegativeTestJSON-693708565"}, "metadata": {"is_admin": 0, "roles": ["9fe2ff9ee4384b1894a90878d3e92bab"]}}}
    2017-04-26 14:57:44,795 22886 DEBUG    [tempest.common.fixed_network] (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict) No valid network provided or created, defaulting to fixed_network_name
    2017-04-26 14:57:45,316 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/os-networks 0.520s
    2017-04-26 14:57:45,316 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: None
        Response - Headers: {'status': '200', u'content-length': '476', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/os-networks', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-fd4fe5a6-671a-401f-a5e2-68a0b0e5ea84', u'content-type': 'application/json', u'connection': 'close'}
            Body: {"networks": [{"cidr_v6": null, "dns2": null, "dns1": null, "gateway": null, "broadcast": null, "netmask": null, "gateway_v6": null, "cidr": null, "label": "rally_verify_3842fe6d_39ORN5Gi", "id": "7446b492-138a-43a3-9059-58ff37d3e59d", "netmask_v6": null}, {"cidr_v6": null, "dns2": null, "dns1": null, "gateway": null, "broadcast": null, "netmask": null, "gateway_v6": null, "cidr": null, "label": "public", "id": "807e3836-656b-457f-bd72-a67b9009d904", "netmask_v6": null}]}
    2017-04-26 14:57:45,317 22886 INFO     [tempest.common.fixed_network] (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict) Found network {'name': u'rally_verify_3842fe6d_39ORN5Gi', u'dns2': None, u'dns1': None, u'cidr': None, u'label': u'rally_verify_3842fe6d_39ORN5Gi', u'broadcast': None, u'netmask': None, u'gateway_v6': None, u'id': u'7446b492-138a-43a3-9059-58ff37d3e59d', u'cidr_v6': None, u'gateway': None, u'netmask_v6': None} available for tenant
    2017-04-26 14:57:45,797 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 500 POST http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers 0.480s
    2017-04-26 14:57:45,798 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: {"server": {"block_device_mapping_v2": [{"source_type": "image", "uuid": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "boot_index": 0, "delete_on_termination": true, "destination_type": "local"}, {"source_type": "volume", "uuid": "866051f0-1c55-43d3-8eda-3d57347bab06", "destination_type": "invalid"}], "flavorRef": "c742038a-4d78-4899-aa5f-269f502c8665", "networks": [{"uuid": "7446b492-138a-43a3-9059-58ff37d3e59d"}], "imageRef": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "name": "tempest-ServersNegativeTestJSON-server-2091650276"}}
        Response - Headers: {'status': '500', u'content-length': '194', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-35a5309d-5c06-4b66-a796-7d3386f8f672', u'content-type': 'application/json; charset=UTF-8', u'connection': 'close'}
            Body: {"computeFault": {"message": "Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.\n<type 'exceptions.ValueError'>", "code": 500}}
    2017-04-26 14:57:45,798 22886 ERROR    [tempest.test] Hints: This test was made for the bug 1651064. The failure could be related to https://launchpad.net/bugs/1651064
    2017-04-26 14:57:45,929 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:tearDown): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5 0.130s
    2017-04-26 14:57:45,930 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
            Body: None
        Response - Headers: {'status': '200', u'content-length': '1676', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7', u'content-type': 'application/json', u'connection': 'close'}
            Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"rally_verify_3842fe6d_39ORN5Gi": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:30:8d:2a", "version": 4, "addr": "10.2.0.4", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "self"}, {"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "bookmark"}], "image": {"id": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/images/2897cc0b-1d3c-40b9-8587-447b8d3e0445";, "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-04-26T21:57:41.000000", "flavor": {"id": "c742038a-4d78-4899-aa5f-269f502c8665", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/flavors/c742038a-4d78-4899-aa5f-269f502c8665";, "rel": "bookmark"}]}, "id": "6239b0ff-6900-4af8-8e49-5c4e0199afa5", "security_groups": [{"name": "default"}], "user_id": "5104ec988e964669997b4f8a80914288", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {}, "status": "ACTIVE", "updated": "2017-04-26T21:57:41Z", "hostId": "e49a0d4ef3c82f4d32305bbfd64ae2131f549992e45b4c1cb9be0de7", "OS-SRV-USG:terminated_at": null, "key_name": null, "name": "tempest-ServersNegativeTestJSON-server-1443765423", "created": "2017-04-26T21:57:35Z", "tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "os-extended-volumes:volumes_attached": [], "config_drive": ""}}


Nova API Log Snippet:
======================
2017-04-26 14:57:45.324 58 DEBUG nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Action: 'create', calling method: <bound method ServersController.create of <nova.api.openstack.compute.servers.ServersController object at 0x763c2d0>>, body: {"server": {"block_device_mapping_v2": [{"source_type": "image", "uuid": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "boot_index": 0, "delete_on_termination": true, "destination_type": "local"}, {"source_type": "volume", "uuid": "866051f0-1c55-43d3-8eda-3d57347bab06", "destination_type": "invalid"}], "flavorRef": "c742038a-4d78-4899-aa5f-269f502c8665", "networks": [{"uuid": "7446b492-138a-43a3-9059-58ff37d3e59d"}], "imageRef": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "name": "tempest-ServersNegativeTestJSON-server-2091650276"}} _process_stack /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:633
2017-04-26 14:57:45.325 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=Keypairs, alias=os-keypairs, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.326 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=AvailabilityZone, alias=os-availability-zone, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.326 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=BlockDeviceMappingV1, alias=os-block-device-mapping-v1, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=BlockDeviceMapping, alias=os-block-device-mapping, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=UserData, alias=os-user-data, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=MultipleCreate, alias=os-multiple-create, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=ConfigDrive, alias=os-config-drive, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=SecurityGroups, alias=os-security-groups, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=SchedulerHints, alias=os-scheduler-hints, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
2017-04-26 14:57:45.339 58 WARNING nova.image.glance [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] No protocol specified in for api_server 'http://20.0.0.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2017-04-26 14:57:45.339 58 WARNING nova.image.glance [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] No protocol specified in for api_server 'http://20.0.0.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
2017-04-26 14:57:45.700 58 DEBUG nova.network.neutronv2.api [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] validate_networks() for [(u'7446b492-138a-43a3-9059-58ff37d3e59d', None, None, None)] validate_networks /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:1618
2017-04-26 14:57:45.752 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/networks.json?id=7446b492-138a-43a3-9059-58ff37d3e59d used request id req-558dd662-790a-4632-8783-99efbc1aa47d _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
2017-04-26 14:57:45.758 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/quotas/84fddff4dcc44eecbfa6e8dc824e291d.json used request id req-96c75958-83be-45b0-b13c-35b3345092c4 _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
2017-04-26 14:57:45.792 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/ports.json?fields=id&tenant_id=84fddff4dcc44eecbfa6e8dc824e291d used request id req-879c805a-d26a-4f35-a4b6-fb39e0f9c3aa _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Unexpected exception in API method
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions Traceback (most recent call last):
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/openstack/extensions.py", line 338, in wrapped
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return f(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py", line 631, in create
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     **create_kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/hooks.py", line 154, in inner
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     rv = f(*args, **kwargs)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/oslo_versionedobjects/fields.py", line 317, in coerce
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     raise ValueError(msg)
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions ValueError: Field value invalid is invalid
2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions
2017-04-26 14:57:45.795 58 INFO nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] HTTP exception thrown: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
<type 'exceptions.ValueError'>
2017-04-26 14:57:45.796 58 DEBUG nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Returning 500 to user: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
<type 'exceptions.ValueError'> __call__ /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:1044
2017-04-26 14:57:45.797 58 INFO nova.osapi_compute.wsgi.server [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] 172.26.232.144,20.0.0.10 "POST /v2/84fddff4dcc44eecbfa6e8dc824e291d/servers HTTP/1.1" status: 500 len: 420 time: 0.4772360
2017-04-26 14:57:45.800 58 DEBUG nova.osapi_compute.wsgi.server [req-94873af5-80cb-434e-9f41-313fdc7bdbda - - - - -] (58) accepted ('20.0.0.10', 42318) server /usr/lib/python2.7/site-packages/eventlet/wsgi.py:867
2017-04-26 14:57:45.806 58 DEBUG nova.api.openstack.wsgi [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Calling method '<bound method ServersController.show of <nova.api.openstack.compute.servers.ServersController object at 0x763c2d0>>' _process_stack /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:636
2017-04-26 14:57:45.806 58 DEBUG nova.compute.api [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] [instance: 6239b0ff-6900-4af8-8e49-5c4e0199afa5] Fetching instance by UUID get /usr/lib/python2.7/site-packages/nova/compute/api.py:2213
2017-04-26 14:57:45.857 58 DEBUG nova.policy [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Policy check for os_compute_api:os-extended-server-attributes failed with credentials {'domain': None, 'project_name': u'tempest-ServersNegativeTestJSON-693708565', 'project_domain': u'default', 'timestamp': '2017-04-26T21:57:45.803085', 'remote_address': '172.26.232.144', 'quota_class': None, 'resource_uuid': None, 'is_admin': False, 'user': u'5104ec988e964669997b4f8a80914288', 'service_catalog': [{u'endpoints': [{u'adminURL': u'http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d', u'region': u'RegionOne', u'internalURL': u'http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d', u'publicURL': u'http://172.26.232.170:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d'}], u'type': u'volumev2', u'name': u'cinderv2'}, {u'endpoints': [{u'adminURL': u'http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d', u'region': u'RegionOne', u'internalURL': u'http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d', u'publicURL': u'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d'}], u'type': u'volume', u'name': u'cinder'}], 'tenant': u'84fddff4dcc44eecbfa6e8dc824e291d', 'read_only': False, 'project_id': u'84fddff4dcc44eecbfa6e8dc824e291d', 'user_id': u'5104ec988e964669997b4f8a80914288', 'show_deleted': False, 'roles': [u'_member_'], 'user_identity': u'5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default', 'is_admin_project': True, 'read_deleted': 'no', 'request_id': 'req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7', 'instance_lock_checked': False, 'user_domain': u'default', 'user_name': u'tempest-ServersNegativeTestJSON-693708565'} authorize /usr/lib/python2.7/site-packages/nova/policy.py:169

** Affects: nova
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to OpenStack Compute (nova).
https://bugs.launchpad.net/bugs/1686540

Title:
  test_create_server_invalid_bdm_in_2nd_dict Failed

Status in OpenStack Compute (nova):
  New

Bug description:
  When I run the test case test_create_server_invalid_bdm_in_2nd_dict in
  tempest - the test case failed with the below result.

  Openstack Version: Newton

  ==============================
  Failed 1 tests - output below:
  ==============================

  tempest.api.compute.servers.test_servers_negative.ServersNegativeTestJSON.test_create_server_invalid_bdm_in_2nd_dict[id-12146ac1-d7df-4928-ad25-b1f99e5286cd,negative]
  ----------------------------------------------------------------------------------------------------------------------------------------------------------------------

  Captured traceback:
  ~~~~~~~~~~~~~~~~~~~
      Traceback (most recent call last):
        File "tempest/test.py", line 163, in wrapper
          raise exc
      tempest.lib.exceptions.ServerFault: Got server fault
      Details: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
      <type 'exceptions.ValueError'>

  
  Captured pythonlogging:
  ~~~~~~~~~~~~~~~~~~~~~~~
      2017-04-26 14:57:42,265 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:setUp): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5 0.138s
      2017-04-26 14:57:42,266 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: None
          Response - Headers: {'status': '200', u'content-length': '1676', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5', u'date': 'Wed, 26 Apr 2017 21:57:42 GMT', u'x-compute-request-id': 'req-b24ea609-e7bb-4806-8679-98c32d75a780', u'content-type': 'application/json', u'connection': 'close'}
              Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"rally_verify_3842fe6d_39ORN5Gi": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:30:8d:2a", "version": 4, "addr": "10.2.0.4", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "self"}, {"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "bookmark"}], "image": {"id": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/images/2897cc0b-1d3c-40b9-8587-447b8d3e0445";, "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-04-26T21:57:41.000000", "flavor": {"id": "c742038a-4d78-4899-aa5f-269f502c8665", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/flavors/c742038a-4d78-4899-aa5f-269f502c8665";, "rel": "bookmark"}]}, "id": "6239b0ff-6900-4af8-8e49-5c4e0199afa5", "security_groups": [{"name": "default"}], "user_id": "5104ec988e964669997b4f8a80914288", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {}, "status": "ACTIVE", "updated": "2017-04-26T21:57:41Z", "hostId": "e49a0d4ef3c82f4d32305bbfd64ae2131f549992e45b4c1cb9be0de7", "OS-SRV-USG:terminated_at": null, "key_name": null, "name": "tempest-ServersNegativeTestJSON-server-1443765423", "created": "2017-04-26T21:57:35Z", "tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "os-extended-volumes:volumes_attached": [], "config_drive": ""}}
      2017-04-26 14:57:42,891 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 POST http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes 0.620s
      2017-04-26 14:57:42,892 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: {"volume": {"display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "size": 1}}
          Response - Headers: {'status': '200', u'content-length': '426', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes', u'x-compute-request-id': 'req-e4dcf42c-7c6c-4339-807a-a12de5627b28', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:42 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-e4dcf42c-7c6c-4339-807a-a12de5627b28'}
              Body: {"volume": {"status": "creating", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.726580", "multiattach": "false", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
      2017-04-26 14:57:43,308 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06 0.415s
      2017-04-26 14:57:43,308 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: None
          Response - Headers: {'status': '200', u'content-length': '494', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06', u'x-compute-request-id': 'req-f652a06e-7b6d-44df-ab25-6001c24ad2a3', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:43 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-f652a06e-7b6d-44df-ab25-6001c24ad2a3'}
              Body: {"volume": {"status": "creating", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.000000", "multiattach": "false", "os-vol-tenant-attr:tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
      2017-04-26 14:57:44,690 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06 0.380s
      2017-04-26 14:57:44,690 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: None
          Response - Headers: {'status': '200', u'content-length': '495', 'content-location': 'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d/volumes/866051f0-1c55-43d3-8eda-3d57347bab06', u'x-compute-request-id': 'req-9bcda74c-d77b-48f6-b899-71ce714fad37', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:44 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-9bcda74c-d77b-48f6-b899-71ce714fad37'}
              Body: {"volume": {"status": "available", "display_name": "tempest-ServersNegativeTestJSON-volume-1449183921", "attachments": [], "availability_zone": "nova", "bootable": "false", "encrypted": false, "created_at": "2017-04-26T21:57:42.000000", "multiattach": "false", "os-vol-tenant-attr:tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "display_description": null, "volume_type": null, "snapshot_id": null, "source_volid": null, "metadata": {}, "id": "866051f0-1c55-43d3-8eda-3d57347bab06", "size": 1}}
      2017-04-26 14:57:44,794 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 POST http://172.26.232.170:5000/v2.0/tokens
      2017-04-26 14:57:44,794 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json'}
              Body: <omitted>
          Response - Headers: {'status': '200', u'content-length': '3687', 'content-location': 'http://172.26.232.170:5000/v2.0/tokens', u'vary': 'X-Auth-Token', u'server': 'Apache', u'connection': 'close', u'date': 'Wed, 26 Apr 2017 21:57:44 GMT', u'content-type': 'application/json', u'x-openstack-request-id': 'req-232fc162-b53d-4a9f-be3d-54fd215f5f2a'}
              Body: {"access": {"token": {"issued_at": "2017-04-26T21:57:44.000000Z", "expires": "2017-04-26T22:57:44Z", "id": "gAAAAABZARfY_lY-W0n7FGdJBvIOQDfNl8AKbsr--ehohO_s3OuNjfdlDeZWuQnhNlYmcjVpTFqDorpngtLW_-meOxierx0yVHOTDr6MxYHIXOspGhtqeoTEtkLlaE6DhR1iPOMLvjtPaUzrzJ39GhzVEpxV4-QVxsiaENHndIuV8omsecZMmQg", "tenant": {"description": "tempest-ServersNegativeTestJSON-693708565-desc", "enabled": true, "id": "84fddff4dcc44eecbfa6e8dc824e291d", "name": "tempest-ServersNegativeTestJSON-693708565"}, "audit_ids": ["bsX5Z8vtSQeRxv-Yzm3v0w"]}, "serviceCatalog": [{"endpoints": [{"adminURL": "http://20.0.0.10:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "557c508ff70f4a23a14842399512e1b8", "publicURL": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "compute", "name": "nova"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9696";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9696";, "id": "15f959f3f19647af8afff2ff77d693ae", "publicURL": "http://172.26.232.170:9696"}], "endpoints_links": [], "type": "network", "name": "neutron"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "4f0a76c7f4c446e795e651eab6a12b5c", "publicURL": "http://172.26.232.170:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "volumev2", "name": "cinderv2"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9292";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9292";, "id": "283a159622f0489da137a3bdb783a3ed", "publicURL": "http://172.26.232.170:9292"}], "endpoints_links": [], "type": "image", "name": "glance"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8000/v1";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8000/v1";, "id": "aea90d48891843e298eb0753f896d41f", "publicURL": "http://172.26.232.170:8000/v1"}], "endpoints_links": [], "type": "cloud-formation", "name": "heat-cfn"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "127c5f41e392456c85ee979fd41aa4d7", "publicURL": "http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "volume", "name": "cinder"}, {"endpoints": [{"adminURL": "http://20.0.0.10:9999";, "region": "RegionOne", "internalURL": "http://20.0.0.10:9999";, "id": "8dc14d561fa94e3594046a65406d5c00", "publicURL": "http://172.26.232.170:9999"}], "endpoints_links": [], "type": "health", "name": "cloudpulse"}, {"endpoints": [{"adminURL": "http://20.0.0.10:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "region": "RegionOne", "internalURL": "http://20.0.0.10:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d";, "id": "0dac27ac914148a0977b05eefc73a1be", "publicURL": "http://172.26.232.170:8004/v1/84fddff4dcc44eecbfa6e8dc824e291d"}], "endpoints_links": [], "type": "orchestration", "name": "heat"}, {"endpoints": [{"adminURL": "http://20.0.0.10:35357/v2.0";, "region": "RegionOne", "internalURL": "http://20.0.0.10:5000/v2.0";, "id": "8e4d3a59a68a41699cedf88776077c72", "publicURL": "http://172.26.232.170:5000/v2.0"}], "endpoints_links": [], "type": "identity", "name": "keystone"}], "user": {"username": "tempest-ServersNegativeTestJSON-693708565", "roles_links": [], "id": "5104ec988e964669997b4f8a80914288", "roles": [{"name": "_member_"}], "name": "tempest-ServersNegativeTestJSON-693708565"}, "metadata": {"is_admin": 0, "roles": ["9fe2ff9ee4384b1894a90878d3e92bab"]}}}
      2017-04-26 14:57:44,795 22886 DEBUG    [tempest.common.fixed_network] (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict) No valid network provided or created, defaulting to fixed_network_name
      2017-04-26 14:57:45,316 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/os-networks 0.520s
      2017-04-26 14:57:45,316 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: None
          Response - Headers: {'status': '200', u'content-length': '476', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/os-networks', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-fd4fe5a6-671a-401f-a5e2-68a0b0e5ea84', u'content-type': 'application/json', u'connection': 'close'}
              Body: {"networks": [{"cidr_v6": null, "dns2": null, "dns1": null, "gateway": null, "broadcast": null, "netmask": null, "gateway_v6": null, "cidr": null, "label": "rally_verify_3842fe6d_39ORN5Gi", "id": "7446b492-138a-43a3-9059-58ff37d3e59d", "netmask_v6": null}, {"cidr_v6": null, "dns2": null, "dns1": null, "gateway": null, "broadcast": null, "netmask": null, "gateway_v6": null, "cidr": null, "label": "public", "id": "807e3836-656b-457f-bd72-a67b9009d904", "netmask_v6": null}]}
      2017-04-26 14:57:45,317 22886 INFO     [tempest.common.fixed_network] (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict) Found network {'name': u'rally_verify_3842fe6d_39ORN5Gi', u'dns2': None, u'dns1': None, u'cidr': None, u'label': u'rally_verify_3842fe6d_39ORN5Gi', u'broadcast': None, u'netmask': None, u'gateway_v6': None, u'id': u'7446b492-138a-43a3-9059-58ff37d3e59d', u'cidr_v6': None, u'gateway': None, u'netmask_v6': None} available for tenant
      2017-04-26 14:57:45,797 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:test_create_server_invalid_bdm_in_2nd_dict): 500 POST http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers 0.480s
      2017-04-26 14:57:45,798 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: {"server": {"block_device_mapping_v2": [{"source_type": "image", "uuid": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "boot_index": 0, "delete_on_termination": true, "destination_type": "local"}, {"source_type": "volume", "uuid": "866051f0-1c55-43d3-8eda-3d57347bab06", "destination_type": "invalid"}], "flavorRef": "c742038a-4d78-4899-aa5f-269f502c8665", "networks": [{"uuid": "7446b492-138a-43a3-9059-58ff37d3e59d"}], "imageRef": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "name": "tempest-ServersNegativeTestJSON-server-2091650276"}}
          Response - Headers: {'status': '500', u'content-length': '194', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-35a5309d-5c06-4b66-a796-7d3386f8f672', u'content-type': 'application/json; charset=UTF-8', u'connection': 'close'}
              Body: {"computeFault": {"message": "Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.\n<type 'exceptions.ValueError'>", "code": 500}}
      2017-04-26 14:57:45,798 22886 ERROR    [tempest.test] Hints: This test was made for the bug 1651064. The failure could be related to https://launchpad.net/bugs/1651064
      2017-04-26 14:57:45,929 22886 INFO     [tempest.lib.common.rest_client] Request (ServersNegativeTestJSON:tearDown): 200 GET http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5 0.130s
      2017-04-26 14:57:45,930 22886 DEBUG    [tempest.lib.common.rest_client] Request - Headers: {'Content-Type': 'application/json', 'Accept': 'application/json', 'X-Auth-Token': '<omitted>'}
              Body: None
          Response - Headers: {'status': '200', u'content-length': '1676', 'content-location': 'http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5', u'date': 'Wed, 26 Apr 2017 21:57:45 GMT', u'x-compute-request-id': 'req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7', u'content-type': 'application/json', u'connection': 'close'}
              Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"rally_verify_3842fe6d_39ORN5Gi": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:30:8d:2a", "version": 4, "addr": "10.2.0.4", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://172.26.232.170:8774/v2/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "self"}, {"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/servers/6239b0ff-6900-4af8-8e49-5c4e0199afa5";, "rel": "bookmark"}], "image": {"id": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/images/2897cc0b-1d3c-40b9-8587-447b8d3e0445";, "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-04-26T21:57:41.000000", "flavor": {"id": "c742038a-4d78-4899-aa5f-269f502c8665", "links": [{"href": "http://172.26.232.170:8774/84fddff4dcc44eecbfa6e8dc824e291d/flavors/c742038a-4d78-4899-aa5f-269f502c8665";, "rel": "bookmark"}]}, "id": "6239b0ff-6900-4af8-8e49-5c4e0199afa5", "security_groups": [{"name": "default"}], "user_id": "5104ec988e964669997b4f8a80914288", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {}, "status": "ACTIVE", "updated": "2017-04-26T21:57:41Z", "hostId": "e49a0d4ef3c82f4d32305bbfd64ae2131f549992e45b4c1cb9be0de7", "OS-SRV-USG:terminated_at": null, "key_name": null, "name": "tempest-ServersNegativeTestJSON-server-1443765423", "created": "2017-04-26T21:57:35Z", "tenant_id": "84fddff4dcc44eecbfa6e8dc824e291d", "os-extended-volumes:volumes_attached": [], "config_drive": ""}}

  
  Nova API Log Snippet:
  ======================
  2017-04-26 14:57:45.324 58 DEBUG nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Action: 'create', calling method: <bound method ServersController.create of <nova.api.openstack.compute.servers.ServersController object at 0x763c2d0>>, body: {"server": {"block_device_mapping_v2": [{"source_type": "image", "uuid": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "boot_index": 0, "delete_on_termination": true, "destination_type": "local"}, {"source_type": "volume", "uuid": "866051f0-1c55-43d3-8eda-3d57347bab06", "destination_type": "invalid"}], "flavorRef": "c742038a-4d78-4899-aa5f-269f502c8665", "networks": [{"uuid": "7446b492-138a-43a3-9059-58ff37d3e59d"}], "imageRef": "2897cc0b-1d3c-40b9-8587-447b8d3e0445", "name": "tempest-ServersNegativeTestJSON-server-2091650276"}} _process_stack /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:633
  2017-04-26 14:57:45.325 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=Keypairs, alias=os-keypairs, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.326 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=AvailabilityZone, alias=os-availability-zone, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.326 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=BlockDeviceMappingV1, alias=os-block-device-mapping-v1, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=BlockDeviceMapping, alias=os-block-device-mapping, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=UserData, alias=os-user-data, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.327 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=MultipleCreate, alias=os-multiple-create, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=ConfigDrive, alias=os-config-drive, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=SecurityGroups, alias=os-security-groups, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.328 58 DEBUG nova.api.openstack.compute.servers [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Running _create_extension_point for <Extension: name=SchedulerHints, alias=os-scheduler-hints, version=1> _create_extension_point /usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py:724
  2017-04-26 14:57:45.339 58 WARNING nova.image.glance [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] No protocol specified in for api_server 'http://20.0.0.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
  2017-04-26 14:57:45.339 58 WARNING nova.image.glance [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] No protocol specified in for api_server 'http://20.0.0.10:9292', please update [glance] api_servers with fully qualified url including scheme (http / https)
  2017-04-26 14:57:45.700 58 DEBUG nova.network.neutronv2.api [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] validate_networks() for [(u'7446b492-138a-43a3-9059-58ff37d3e59d', None, None, None)] validate_networks /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:1618
  2017-04-26 14:57:45.752 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/networks.json?id=7446b492-138a-43a3-9059-58ff37d3e59d used request id req-558dd662-790a-4632-8783-99efbc1aa47d _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
  2017-04-26 14:57:45.758 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/quotas/84fddff4dcc44eecbfa6e8dc824e291d.json used request id req-96c75958-83be-45b0-b13c-35b3345092c4 _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
  2017-04-26 14:57:45.792 58 DEBUG neutronclient.v2_0.client [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] GET call to neutron for http://20.0.0.10:9696/v2.0/ports.json?fields=id&tenant_id=84fddff4dcc44eecbfa6e8dc824e291d used request id req-879c805a-d26a-4f35-a4b6-fb39e0f9c3aa _append_request_id /usr/lib/python2.7/site-packages/neutronclient/v2_0/client.py:127
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Unexpected exception in API method
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions Traceback (most recent call last):
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/openstack/extensions.py", line 338, in wrapped
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return f(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/validation/__init__.py", line 73, in wrapper
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     return func(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/api/openstack/compute/servers.py", line 631, in create
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     **create_kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/nova/hooks.py", line 154, in inner
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     rv = f(*args, **kwargs)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions   File "/usr/lib/python2.7/site-packages/oslo_versionedobjects/fields.py", line 317, in coerce
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions     raise ValueError(msg)
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions ValueError: Field value invalid is invalid
  2017-04-26 14:57:45.793 58 ERROR nova.api.openstack.extensions
  2017-04-26 14:57:45.795 58 INFO nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] HTTP exception thrown: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
  <type 'exceptions.ValueError'>
  2017-04-26 14:57:45.796 58 DEBUG nova.api.openstack.wsgi [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Returning 500 to user: Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible.
  <type 'exceptions.ValueError'> __call__ /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:1044
  2017-04-26 14:57:45.797 58 INFO nova.osapi_compute.wsgi.server [req-35a5309d-5c06-4b66-a796-7d3386f8f672 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] 172.26.232.144,20.0.0.10 "POST /v2/84fddff4dcc44eecbfa6e8dc824e291d/servers HTTP/1.1" status: 500 len: 420 time: 0.4772360
  2017-04-26 14:57:45.800 58 DEBUG nova.osapi_compute.wsgi.server [req-94873af5-80cb-434e-9f41-313fdc7bdbda - - - - -] (58) accepted ('20.0.0.10', 42318) server /usr/lib/python2.7/site-packages/eventlet/wsgi.py:867
  2017-04-26 14:57:45.806 58 DEBUG nova.api.openstack.wsgi [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Calling method '<bound method ServersController.show of <nova.api.openstack.compute.servers.ServersController object at 0x763c2d0>>' _process_stack /usr/lib/python2.7/site-packages/nova/api/openstack/wsgi.py:636
  2017-04-26 14:57:45.806 58 DEBUG nova.compute.api [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] [instance: 6239b0ff-6900-4af8-8e49-5c4e0199afa5] Fetching instance by UUID get /usr/lib/python2.7/site-packages/nova/compute/api.py:2213
  2017-04-26 14:57:45.857 58 DEBUG nova.policy [req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7 5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default] Policy check for os_compute_api:os-extended-server-attributes failed with credentials {'domain': None, 'project_name': u'tempest-ServersNegativeTestJSON-693708565', 'project_domain': u'default', 'timestamp': '2017-04-26T21:57:45.803085', 'remote_address': '172.26.232.144', 'quota_class': None, 'resource_uuid': None, 'is_admin': False, 'user': u'5104ec988e964669997b4f8a80914288', 'service_catalog': [{u'endpoints': [{u'adminURL': u'http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d', u'region': u'RegionOne', u'internalURL': u'http://20.0.0.10:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d', u'publicURL': u'http://172.26.232.170:8776/v2/84fddff4dcc44eecbfa6e8dc824e291d'}], u'type': u'volumev2', u'name': u'cinderv2'}, {u'endpoints': [{u'adminURL': u'http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d', u'region': u'RegionOne', u'internalURL': u'http://20.0.0.10:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d', u'publicURL': u'http://172.26.232.170:8776/v1/84fddff4dcc44eecbfa6e8dc824e291d'}], u'type': u'volume', u'name': u'cinder'}], 'tenant': u'84fddff4dcc44eecbfa6e8dc824e291d', 'read_only': False, 'project_id': u'84fddff4dcc44eecbfa6e8dc824e291d', 'user_id': u'5104ec988e964669997b4f8a80914288', 'show_deleted': False, 'roles': [u'_member_'], 'user_identity': u'5104ec988e964669997b4f8a80914288 84fddff4dcc44eecbfa6e8dc824e291d - default default', 'is_admin_project': True, 'read_deleted': 'no', 'request_id': 'req-4937b915-7e8a-4cce-9c45-1d3cb2c7ffd7', 'instance_lock_checked': False, 'user_domain': u'default', 'user_name': u'tempest-ServersNegativeTestJSON-693708565'} authorize /usr/lib/python2.7/site-packages/nova/policy.py:169

To manage notifications about this bug go to:
https://bugs.launchpad.net/nova/+bug/1686540/+subscriptions
Follow ups

[Bug 1686540] Re: test_create_server_invalid_bdm_in_2nd_dict Failed
From: Launchpad Bug Tracker, 2017-09-23
[Bug 1686540] Re: test_create_server_invalid_bdm_in_2nd_dict Failed
From: Saravana Ganeshan, 2017-04-27