networking-midonet

metadata POST test in test_server_basic_ops is failing on gate

Bug #1660209 reported by YAMAMOTO Takashi on 2017-01-29

This bug affects 1 person

Affects		Status	Importance	Assigned to	Milestone
	networking-midonet	Fix Released	Critical	YAMAMOTO Takashi	networking-midonet 4.0.0

Bug Description

eg. http://logs.openstack.org/80/426080/1/check/gate-tempest-dsvm-networking-midonet-ml2-ubuntu-xenial/20dbe87/logs/testr_results.html.gz

ft1.1: tempest.scenario.test_server_basic_ops.TestServerBasicOps.test_server_basic_ops[compute,id-7fff3fb3-91d8-4fd0-bd7d-0204f1f180ba,network,smoke]_StringException: Empty attachments:
stderr
stdout

pythonlogging:'': {{{
2017-01-27 06:35:50,896 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:8774/v2.1/os-keypairs 0.136s
2017-01-27 06:35:50,896 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"keypair": {"name": "tempest-TestServerBasicOps-716513336"}}
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-a3871e44-210c-476e-8911-964fd652184c', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-keypairs', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '2311', u'date': 'Fri, 27 Jan 2017 06:35:50 GMT'}
        Body: {"keypair": {"public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCoFUl/6JHPTJmnVaGndeS4oVSb5yh/y2z+Yxl4pkeRNw/mufoEIw9dtLNcjscvZImu4v31JnDIMhTe6PJkS6xKNgGPp8zE3ip9h+nA/yraxU9vIXts5qqx+jXXSHlUFDiAyqMUWlrjIZkaLLj7SgVgdqby5Tbbd457+nLVe+drzMPhnpYpKllhXy8IM9Q32DNKLYYQ/z7oX0Jf5tw22KhuSU60XXNR0p/Wlxntl5akQ6UaBvkjp24u3D+LXpAl6CzEisn6TzK5W3J/8I86AcPAnEe3PnEy05cPOU3yLo0oiBSjE7LxRh4hT3GxmyEZ+hydDARXbuRx4HQ51LxFxZkT Generated-by-Nova", "private_key": "-----BEGIN RSA PRIVATE KEY-----\nMIIEowIBAAKCAQEAqBVJf+iRz0yZp1Whp3XkuKFUm+cof8ts/mMZeKZHkTcP5rn6\nBCMPXbSzXI7HL2SJruL99SZwyDIU3ujyZEusSjYBj6fMxN4qfYfpwP8q2sVPbyF7\nbOaqsfo110h5VBQ4gMqjFFpa4yGZGiy4+0oFYHam8uU223eOe/py1Xvna8zD4Z6W\nKSpZYV8vCDPUN9gzSi2GEP8+6F9CX+bcNtiobklOtF1zUdKf1pcZ7ZeWpEOlGgb5\nI6duLtw/i16QJegsxIrJ+k8yuVtyf/CPOgHDwJxHtz5xMtOXDzlN8i6NKIgUoxOy\n8UYeIU9xsZshGfocnQwEV27kceB0OdS8RcWZEwIDAQABAoIBABFzRFI3Pwwak8k/\npqzQHJLejP4ynY3G/OXcPAS+nqeA4/G8VSygxzOfUw/E8wEEmxbBEkSlXQgryubp\noGCxSo3Z1YeokCXQOvEVbztOQpC782dCPqIrwIUByR00tQP5pXQZlZzDVLRIBLEm\n+317TduZ9fM5wNxAKVbNxaZMWtZiHM+So0scG+kcwKSCoT1KaJzuuAmzyZJl+Ygf\nu7sFfcfwylKrIRkHlZphK6svNGk9Cet6x9a73/TSHDd3dWeuU6gZCt0zmJ0xwi4v\n1jfvaWFWYpl0F1NCnN62ipffMBE6d3MpVQB4Y2WQrQhIkr5vrrN60iueprmpeaiZ\nBDrn5yECgYEA023U5ugoKyoq6pOTh3daAn7XcE2mUq7yIqCI/UBNT3db6J4XXLrG\nvTh1hjbz5R72MylTBsDnMZtnrnVwjgvJ5wxqL3zJNeq49dEw4tq1KqAVd/e6X3N6\n2UiFB5qHnUZzhRp9KHFBSmMmGf7yiWxg3HF07d+zz0zvhPH+n/SM5v8CgYEAy4Q2\nid0/7BmuaDob8dpkYi7Xem542yK3xkWQmEZp6AkxCdneZxn0m/WeHRs3MsiFdm+M\nPZQgJ3rYnCnO0m0NvgcdB9G17bo6XWF3axBgQ51zP7nYnvDJwv5pRtqFE6+308yV\nocJ8x8ej5vAAhVq7hZUIgKDv0dvw9dMZM8xTQe0CgYBOK/KmuTl41Ctv9cZ7NLaz\n63/uRtdzBLoD8YUU1bx/4Cq8NA+BOpGWjpRtXk4I7cyrl+DZw2L3pXTNPSCzdEpf\nQejUy/d9Ft7yDDld9u066FtnstFWz1Shl72P1SPFE7JMiUGzPUSdo1EIFYpJIygg\n+VX5SVerEtAQQIUbUQjzswKBgFbnWSabrJ0UBk6Jab9nw2/NksO8Aq2IcLMgp3Lu\ntnCX5DHRr8+U3MfwJRlGstZjm+IMFfWBmA1jvrVIgQGIAdNAVFwvZ6Fg4WVYpla4\ngnK8/CadJGwSZAV4/9l6nFLguP2fIdIWK5RfopSNrZtEOEj9b8kFcALMCWPfRabB\nvFetAoGBAKOG4l97jc4ofwbSLEwqFRpB0YsEjDoql7fUY0ObutcGseKAB+ZV39n4\nMnl3GZsL0JR7VQc1vKY6nFX20utU1hlC49LgZj8ur8HRCVoAnu9IwuM8vsMc/HtU\nafhr+64ZSUB8fN4pYjKBXGVPQyjxsuc+K4CrWZ7dpYitJEMLAWyW\n-----END RSA PRIVATE KEY-----\n", "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "name": "tempest-TestServerBasicOps-716513336", "fingerprint": "56:35:ed:70:95:43:d7:98:7f:c4:e7:a6:46:23:73:d8"}}
2017-01-27 06:35:51,102 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:8774/v2.1/os-security-groups 0.203s
2017-01-27 06:35:51,102 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"security_group": {"description": "tempest-TestServerBasicOps-120311020 description", "name": "tempest-TestServerBasicOps-120311020"}}
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-0d536e29-2263-46f1-9326-a9be1cfb4cc5', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-security-groups', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '243', u'date': 'Fri, 27 Jan 2017 06:35:51 GMT'}
        Body: {"security_group": {"rules": [], "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "id": "27722085-7fb6-4ba1-896a-91c2a6559acd", "name": "tempest-TestServerBasicOps-120311020", "description": "tempest-TestServerBasicOps-120311020 description"}}
2017-01-27 06:35:51,688 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:8774/v2.1/os-security-group-rules 0.583s
2017-01-27 06:35:51,688 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"security_group_rule": {"cidr": "0.0.0.0/0", "from_port": 22, "to_port": 22, "ip_protocol": "tcp", "parent_group_id": "27722085-7fb6-4ba1-896a-91c2a6559acd"}}
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-53a5637d-e73f-4b28-bc1b-1a8e9ce5cc63', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-security-group-rules', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '232', u'date': 'Fri, 27 Jan 2017 06:35:51 GMT'}
        Body: {"security_group_rule": {"from_port": 22, "group": {}, "ip_protocol": "tcp", "to_port": 22, "parent_group_id": "27722085-7fb6-4ba1-896a-91c2a6559acd", "ip_range": {"cidr": "0.0.0.0/0"}, "id": "d5c084a2-820b-417a-9f6e-6a0abfce5665"}}
2017-01-27 06:35:52,477 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:8774/v2.1/os-security-group-rules 0.786s
2017-01-27 06:35:52,477 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"security_group_rule": {"cidr": "0.0.0.0/0", "from_port": -1, "to_port": -1, "ip_protocol": "icmp", "parent_group_id": "27722085-7fb6-4ba1-896a-91c2a6559acd"}}
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-4f7f75f0-d6d6-4fc4-b009-31a994e7c0c4', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-security-group-rules', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '233', u'date': 'Fri, 27 Jan 2017 06:35:52 GMT'}
        Body: {"security_group_rule": {"from_port": -1, "group": {}, "ip_protocol": "icmp", "to_port": -1, "parent_group_id": "27722085-7fb6-4ba1-896a-91c2a6559acd", "ip_range": {"cidr": "0.0.0.0/0"}, "id": "5021a845-4ade-412c-b5db-c3465533eb2a"}}
2017-01-27 06:35:52,555 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:5000/v2.0/tokens
2017-01-27 06:35:52,556 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: <omitted>
    Response - Headers: {'status': '200', u'vary': 'X-Auth-Token', u'connection': 'close', u'x-openstack-request-id': 'req-086861fc-789b-4a24-9de0-5d5ef65f2d9d', u'server': 'Apache/2.4.18 (Ubuntu)', u'content-type': 'application/json', u'content-length': '2374', u'date': 'Fri, 27 Jan 2017 06:35:52 GMT', 'content-location': 'http://10.22.229.96:5000/v2.0/tokens'}
        Body: {"access": {"token": {"issued_at": "2017-01-27T06:35:52.000000Z", "expires": "2017-01-27T07:35:52.000000Z", "id": "gAAAAABYiupICZ9cgfRYRk67gQCmGzsZyOa1pkOl_fQsMM5xsp1J_Cr9QxvLB8f3QHICVECFZIQ43F5ac8gr68pBjiRsCQC37is1_sQT5er5YDSkvWo_PLfNlnBDhZIJ9KPbZr0IgkxesOyJRO29_g7P4nZ4aOTf8RTmx5f18AJrCxv0RtjxE8Q", "tenant": {"description": "tempest-TestServerBasicOps-834325914-desc", "enabled": true, "id": "fca606d5248f45c3b8e738af4cb9b721", "name": "tempest-TestServerBasicOps-834325914"}, "audit_ids": ["Z_Oe3bsqRkuCndZSx5D-Rw"]}, "serviceCatalog": [{"endpoints": [{"adminURL": "http://10.22.229.96:9292", "region": "RegionOne", "id": "16306b3a44af46528c0b11c4ef8554f0", "internalURL": "http://10.22.229.96:9292", "publicURL": "http://10.22.229.96:9292"}], "endpoints_links": [], "type": "image", "name": "glance"}, {"endpoints": [{"adminURL": "http://10.22.229.96:8774/v2.1", "region": "RegionOne", "id": "30f891cf20304147b2bff2e109735f1c", "internalURL": "http://10.22.229.96:8774/v2.1", "publicURL": "http://10.22.229.96:8774/v2.1"}], "endpoints_links": [], "type": "compute", "name": "nova"}, {"endpoints": [{"adminURL": "http://10.22.229.96:9696/", "region": "RegionOne", "id": "3543aecfb7654df1a1fbef10a8803f61", "internalURL": "http://10.22.229.96:9696/", "publicURL": "http://10.22.229.96:9696/"}], "endpoints_links": [], "type": "network", "name": "neutron"}, {"endpoints": [{"adminURL": "http://10.22.229.96/identity_admin", "region": "RegionOne", "id": "c6b4230b9f804e5da423112b4b38de04", "internalURL": "http://10.22.229.96/identity", "publicURL": "http://10.22.229.96/identity"}], "endpoints_links": [], "type": "identity", "name": "keystone"}, {"endpoints": [{"adminURL": "http://10.22.229.96:8774/v2/fca606d5248f45c3b8e738af4cb9b721", "region": "RegionOne", "id": "a8d9d0465d2245cb8cbdd08074e107db", "internalURL": "http://10.22.229.96:8774/v2/fca606d5248f45c3b8e738af4cb9b721", "publicURL": "http://10.22.229.96:8774/v2/fca606d5248f45c3b8e738af4cb9b721"}], "endpoints_links": [], "type": "compute_legacy", "name": "nova_legacy"}], "user": {"username": "tempest-TestServerBasicOps-834325914", "roles_links": [], "id": "aa7d4d8cba17439882cbcc8d937f6f16", "roles": [{"name": "Member"}, {"name": "_member_"}], "name": "tempest-TestServerBasicOps-834325914"}, "metadata": {"is_admin": 0, "roles": ["61ba78bb126b41338bf225a10be3e478", "9fe2ff9ee4384b1894a90878d3e92bab"]}}}
2017-01-27 06:35:52,556 561 INFO [tempest.common.fixed_network] (TestServerBasicOps:test_server_basic_ops) Found network {u'created_at': u'2017-01-27T06:35:47Z', u'ipv6_address_scope': None, u'id': u'e466da9b-9491-4505-ba3e-8f487f627f44', u'updated_at': u'2017-01-27T06:35:47Z', u'subnets': [], u'ipv4_address_scope': None, u'tenant_id': u'fca606d5248f45c3b8e738af4cb9b721', u'admin_state_up': True, u'qos_policy_id': None, u'project_id': u'fca606d5248f45c3b8e738af4cb9b721', u'availability_zone_hints': [], u'port_security_enabled': True, u'availability_zones': [], u'status': u'ACTIVE', u'provider:physical_network': None, u'provider:segmentation_id': None, u'mtu': 0, u'provider:network_type': u'midonet', u'tags': [], u'router:external': False, u'name': u'tempest-TestServerBasicOps-612993174-network', u'shared': False, u'revision_number': 3, u'description': u''} available for tenant
2017-01-27 06:35:54,026 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 202 POST http://10.22.229.96:8774/v2.1/servers 1.469s
2017-01-27 06:35:54,027 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"server": {"flavorRef": "42", "key_name": "tempest-TestServerBasicOps-716513336", "imageRef": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "metadata": {"meta2": "data2", "meta1": "data1", "metaN": "dataN"}, "name": "tempest-TestServerBasicOps-server-147715367", "config_drive": true, "networks": [{"uuid": "e466da9b-9491-4505-ba3e-8f487f627f44"}]}}
    Response - Headers: {'status': '202', u'vary': 'X-OpenStack-Nova-API-Version', u'location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'x-compute-request-id': 'req-bd5f66b9-30c1-4444-b9ee-8aa94bc97f25', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '405', u'date': 'Fri, 27 Jan 2017 06:35:54 GMT'}
        Body: {"server": {"security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "OS-DCF:diskConfig": "MANUAL", "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "adminPass": "2cncDsXXfYLE"}}
2017-01-27 06:35:54,297 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.268s
2017-01-27 06:35:54,297 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-151d0b72-c844-4e25-97f8-9c05be209405', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1291', u'date': 'Fri, 27 Jan 2017 06:35:54 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "scheduling", "addresses": {}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 0, "OS-EXT-AZ:availability_zone": "", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "BUILD", "updated": "2017-01-27T06:35:54Z", "hostId": "", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:35:55,721 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.417s
2017-01-27 06:35:55,721 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-3082c1a2-e585-4649-a3bf-6c718e37954c', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1349', u'date': 'Fri, 27 Jan 2017 06:35:55 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 0, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "BUILD", "updated": "2017-01-27T06:35:55Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:35:55,726 561 INFO [tempest.common.waiters] State transition "BUILD/scheduling" ==> "BUILD/spawning" after 1 second wait
2017-01-27 06:35:57,273 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.545s
2017-01-27 06:35:57,273 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-27fc89c7-b79c-4224-a975-59683f1a7b99', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1420', u'date': 'Fri, 27 Jan 2017 06:35:57 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 0, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "BUILD", "updated": "2017-01-27T06:35:55Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:35:58,979 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.699s
2017-01-27 06:35:58,979 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-d68c2606-bff5-4077-afe5-d146fc780947', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1420', u'date': 'Fri, 27 Jan 2017 06:35:58 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 0, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "BUILD", "updated": "2017-01-27T06:35:55Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:36:00,561 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.575s
2017-01-27 06:36:00,562 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-af082813-d274-4cae-8e46-725d24ac6517', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1597', u'date': 'Fri, 27 Jan 2017 06:36:00 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"tempest-TestServerBasicOps-612993174-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:64:d9:f6", "version": 4, "addr": "10.1.0.8", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-01-27T06:35:59.000000", "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "ACTIVE", "updated": "2017-01-27T06:35:59Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:36:00,566 561 INFO [tempest.common.waiters] State transition "BUILD/spawning" ==> "ACTIVE/None" after 6 second wait
2017-01-27 06:36:01,271 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.704s
2017-01-27 06:36:01,271 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-8207f733-6266-471a-8b2d-adbc6b89af4f', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1597', u'date': 'Fri, 27 Jan 2017 06:36:01 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"tempest-TestServerBasicOps-612993174-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:64:d9:f6", "version": 4, "addr": "10.1.0.8", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-01-27T06:35:59.000000", "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "ACTIVE", "updated": "2017-01-27T06:35:59Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:36:04,120 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.22.229.96:8774/v2.1/os-floating-ips 2.843s
2017-01-27 06:36:04,120 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"pool": null}
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-26ac1175-932c-4e56-a49a-d992fc5a47fa', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-floating-ips', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '141', u'date': 'Fri, 27 Jan 2017 06:36:04 GMT'}
        Body: {"floating_ip": {"instance_id": null, "ip": "172.24.5.16", "fixed_ip": null, "id": "74cb1d22-a6b5-4611-a5dd-d7b1b61ccbef", "pool": "public"}}
2017-01-27 06:36:05,984 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 202 POST http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3/action 1.862s
2017-01-27 06:36:05,985 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: {"addFloatingIp": {"address": "172.24.5.16"}}
    Response - Headers: {'status': '202', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-b2c9f63c-915c-4f8a-b1a0-658e9e9c1f08', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3/action', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'text/html; charset=UTF-8', u'content-length': '0', u'date': 'Fri, 27 Jan 2017 06:36:05 GMT'}
        Body:
2017-01-27 06:36:05,985 561 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.16:22' as 'cirros' with public key authentication
2017-01-27 06:36:13,024 561 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-01-27 06:36:13,121 561 INFO [paramiko.transport] Authentication (publickey) successful!
2017-01-27 06:36:13,124 561 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.16 successfully created
2017-01-27 06:36:13,238 561 DEBUG [tempest.common.utils.linux.remote_client] Remote command: set -eu -o pipefail; PATH=$PATH:/sbin; curl http://169.254.169.254/latest/meta-data/public-ipv4
2017-01-27 06:36:13,239 561 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.16:22' as 'cirros' with public key authentication
2017-01-27 06:36:13,250 561 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-01-27 06:36:13,353 561 INFO [paramiko.transport] Authentication (publickey) successful!
2017-01-27 06:36:13,362 561 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.16 successfully created
2017-01-27 06:36:14,066 561 DEBUG [tempest.common.utils.linux.remote_client] Remote command: set -eu -o pipefail; PATH=$PATH:/sbin; curl -X POST -d testtesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttest http://169.254.169.254/openstack/2013-10-17/password
2017-01-27 06:36:14,067 561 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.16:22' as 'cirros' with public key authentication
2017-01-27 06:36:14,093 561 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-01-27 06:36:14,224 561 INFO [paramiko.transport] Authentication (publickey) successful!
2017-01-27 06:36:14,226 561 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.16 successfully created
2017-01-27 06:36:14,571 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3/os-server-password 0.139s
2017-01-27 06:36:14,571 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-410572d1-f48a-4f68-a19b-82a872f3f3b3', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3/os-server-password', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '16', u'date': 'Fri, 27 Jan 2017 06:36:14 GMT'}
        Body: {"password": ""}
2017-01-27 06:36:16,587 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.22.229.96:8774/v2.1/os-floating-ips/74cb1d22-a6b5-4611-a5dd-d7b1b61ccbef 2.008s
2017-01-27 06:36:16,587 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '202', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-d66f0e91-04d4-4e25-a471-cf20d4038b15', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-floating-ips/74cb1d22-a6b5-4611-a5dd-d7b1b61ccbef', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '0', u'date': 'Fri, 27 Jan 2017 06:36:16 GMT'}
        Body:
2017-01-27 06:36:16,787 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 204 DELETE http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.199s
2017-01-27 06:36:16,787 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '204', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-c698a4bf-7543-498d-8031-2c06c19774ae', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '0', u'date': 'Fri, 27 Jan 2017 06:36:16 GMT'}
        Body:
2017-01-27 06:36:17,134 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.347s
2017-01-27 06:36:17,134 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-77c96a33-c3c4-422b-af46-89a9e1ea9c1f', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1603', u'date': 'Fri, 27 Jan 2017 06:36:17 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "deleting", "addresses": {"tempest-TestServerBasicOps-612993174-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:64:d9:f6", "version": 4, "addr": "10.1.0.8", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-01-27T06:35:59.000000", "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "ACTIVE", "updated": "2017-01-27T06:36:17Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:36:18,625 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 200 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.484s
2017-01-27 06:36:18,626 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '200', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-1389fcbc-38b3-4319-9b74-11c70fec856c', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '1603', u'date': 'Fri, 27 Jan 2017 06:36:18 GMT'}
        Body: {"server": {"OS-EXT-STS:task_state": "deleting", "addresses": {"tempest-TestServerBasicOps-612993174-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:64:d9:f6", "version": 4, "addr": "10.1.0.8", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "self"}, {"href": "http://10.22.229.96:8774/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "rel": "bookmark"}], "image": {"id": "c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "links": [{"href": "http://10.22.229.96:8774/images/c49045cc-c7af-4d3b-9afb-c8439ac4fc23", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-01-27T06:35:59.000000", "flavor": {"id": "42", "links": [{"href": "http://10.22.229.96:8774/flavors/42", "rel": "bookmark"}]}, "id": "7c4b6a64-3eb8-4954-8658-a498fb9e63e3", "security_groups": [{"name": "tempest-TestServerBasicOps-120311020"}], "user_id": "aa7d4d8cba17439882cbcc8d937f6f16", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "metadata": {"meta1": "data1", "meta2": "data2", "metaN": "dataN"}, "status": "ACTIVE", "updated": "2017-01-27T06:36:18Z", "hostId": "d297ed4bf4b550fbb7c96954697314db89545ff5cd9b9c182bf0cf64", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-716513336", "name": "tempest-TestServerBasicOps-server-147715367", "created": "2017-01-27T06:35:54Z", "tenant_id": "fca606d5248f45c3b8e738af4cb9b721", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-01-27 06:36:19,767 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 404 GET http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3 0.134s
2017-01-27 06:36:19,767 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '404', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-8f70b54e-2db2-476e-bf9d-5c7ae9084205', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/servers/7c4b6a64-3eb8-4954-8658-a498fb9e63e3', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json; charset=UTF-8', u'content-length': '111', u'date': 'Fri, 27 Jan 2017 06:36:19 GMT'}
        Body: {"itemNotFound": {"message": "Instance 7c4b6a64-3eb8-4954-8658-a498fb9e63e3 could not be found.", "code": 404}}
2017-01-27 06:36:20,105 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.22.229.96:8774/v2.1/os-security-groups/27722085-7fb6-4ba1-896a-91c2a6559acd 0.338s
2017-01-27 06:36:20,105 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '202', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-827fa8ef-55bf-48b1-9770-96b796fd50b1', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-security-groups/27722085-7fb6-4ba1-896a-91c2a6559acd', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '0', u'date': 'Fri, 27 Jan 2017 06:36:20 GMT'}
        Body:
2017-01-27 06:36:20,153 561 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.22.229.96:8774/v2.1/os-keypairs/tempest-TestServerBasicOps-716513336 0.048s
2017-01-27 06:36:20,154 561 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'X-Auth-Token': '<omitted>', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {'status': '202', u'vary': 'X-OpenStack-Nova-API-Version', u'x-compute-request-id': 'req-0db0f81a-d4e1-460c-a368-c3922a13d43d', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.22.229.96:8774/v2.1/os-keypairs/tempest-TestServerBasicOps-716513336', u'connection': 'close', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json', u'content-length': '0', u'date': 'Fri, 27 Jan 2017 06:36:20 GMT'}
        Body:
}}}

Traceback (most recent call last):
  File "tempest/test.py", line 99, in wrapper
    return f(self, *func_args, **func_kwargs)
  File "tempest/scenario/test_server_basic_ops.py", line 143, in test_server_basic_ops
    self.verify_metadata()
  File "tempest/scenario/test_server_basic_ops.py", line 89, in verify_metadata
    self.assertEqual(data, result['password'])
  File "/usr/local/lib/python2.7/dist-packages/testtools/testcase.py", line 411, in assertEqual
    self.assertThat(observed, matcher, message)
  File "/usr/local/lib/python2.7/dist-packages/testtools/testcase.py", line 498, in assertThat
    raise mismatch_error
testtools.matchers._impl.MismatchError: !=:
reference = 'testtesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttesttest'
actual = u''

Tags:

Revision history for this message

YAMAMOTO Takashi (yamamoto) wrote on 2017-01-29:

Download full text (3.4 KiB)

it looks like a backend issue.

2017.01.27 06:36:14.398 DEBUG [qtp822332371-132] metadata - POST request from instance:7c4b6a64-3eb8-4954-8658-a498fb9e63e3 to http://10.22.229.96:8775/openstack/2013-10-17/password
2017.01.27 06:36:14.409 WARN [qtp822332371-132] HttpChannel - //169.254.169.254/openstack/2013-10-17/password
com.sun.jersey.api.client.ClientHandlerException: A message body writer for Java type, class [B, and MIME media type, application/octet-stream, was not found
        at com.sun.jersey.api.client.RequestWriter.writeRequestEntity(RequestWriter.java:288) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.client.urlconnection.URLConnectionClientHandler._invoke(URLConnectionClientHandler.java:217) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.client.urlconnection.URLConnectionClientHandler.handle(URLConnectionClientHandler.java:153) ~[midolman.jar:5.6-SNAPSHOT]
        ... 19 common frames omitted
Wrapped by: com.sun.jersey.api.client.ClientHandlerException: com.sun.jersey.api.client.ClientHandlerException: A message body writer for Java type, class [B, and MIME media type, application/octet-stream, was not found
        at com.sun.jersey.client.urlconnection.URLConnectionClientHandler.handle(URLConnectionClientHandler.java:155) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.api.client.Client.handle(Client.java:652) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.api.client.WebResource.handle(WebResource.java:682) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.api.client.WebResource.access$200(WebResource.java:74) ~[midolman.jar:5.6-SNAPSHOT]
        at com.sun.jersey.api.client.WebResource$Builder.post(WebResource.java:570) ~[midolman.jar:5.6-SNAPSHOT]
        at org.midonet.midolman.openstack.metadata.NovaMetadataClient$.proxyRequest(NovaMetadataClient.scala:112) ~[midolman.jar:5.6-SNAPSHOT]
        at org.midonet.midolman.openstack.metadata.NovaMetadataClient$.proxyRequest(NovaMetadataClient.scala:78) ~[midolman.jar:5.6-SNAPSHOT]
        at org.midonet.midolman.openstack.metadata.ProxyHandler.handle(ProxyHandler.scala:34) ~[midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:119) ~[midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.server.Server.handle(Server.java:517) ~[midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:302) ~[midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:242) [midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:245) [midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:95) [midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:75) [midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceAndRun(ExecuteProduceConsume.java:213) [midolman.jar:5.6-SNAPSHOT]
        at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:147) [midolman.jar...

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.

tags:	added: gate-failure
Changed in networking-midonet:
assignee:	nobody → YAMAMOTO Takashi (yamamoto)
importance:	Undecided → Critical
milestone:	none → 4.0.0
status:	New → In Progress

Changed in networking-midonet:
status:	In Progress → Fix Released