test_server_basic_ops: mounting /dev/sr0 on /mnt failed: Device or resource busy

Bug #1663529 reported by YAMAMOTO Takashi
14
This bug affects 2 people
Affects Status Importance Assigned to Milestone
tempest
Invalid
High
Unassigned

Bug Description

eg. http://logs.openstack.org/77/423077/1/gate/gate-tempest-dsvm-networking-midonet-ml2-ubuntu-xenial/71d495f/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-02-10 07:25:30,701 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:8774/v2.1/os-keypairs 0.254s
2017-02-10 07:25:30,701 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"keypair": {"name": "tempest-TestServerBasicOps-723183342"}}
    Response - Headers: {u'x-compute-request-id': 'req-ab09112e-9dfe-45f6-9d6b-6b32181e4392', u'content-length': '2315', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:30 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-keypairs', 'status': '200', u'content-type': 'application/json'}
        Body: {"keypair": {"public_key": "ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDEPAnE0YWM1yGgjULStfJOi7VmYUfDZwFnM6PVBnrIG1fr8q43b4HuAKJGE4txQeW8c5DvUstsUOqn/8ZVcpCSL92yBNpZKuPWXPmE5TqQnqq2JPJmj83jUj+OOFUMw1g5ULYyFVgXHTOYreoFSWUMwpmcLz6RRpilaPxLP+jvjTtmELG/zZmtt6vOpqSrNjXQlwgV5YjzuX75+JLYL69VwdZREoT5trAeKv3AGFGBohZvJcs4bBpPSHicsoDvcup43BZ3dS+u6+iUpissQk0bjZI4j+E48W4w44Ro4F0sPGtSXSjoT0Lzm4JjbIVbbwS6jUXWSn1rSU2qehVaE5zJ Generated-by-Nova", "private_key": "-----BEGIN RSA PRIVATE KEY-----\nMIIEpAIBAAKCAQEAxDwJxNGFjNchoI1C0rXyTou1ZmFHw2cBZzOj1QZ6yBtX6/Ku\nN2+B7gCiRhOLcUHlvHOQ71LLbFDqp//GVXKQki/dsgTaWSrj1lz5hOU6kJ6qtiTy\nZo/N41I/jjhVDMNYOVC2MhVYFx0zmK3qBUllDMKZnC8+kUaYpWj8Sz/o7407ZhCx\nv82ZrberzqakqzY10JcIFeWI87l++fiS2C+vVcHWURKE+bawHir9wBhRgaIWbyXL\nOGwaT0h4nLKA73LqeNwWd3UvruvolKYrLEJNG42SOI/hOPFuMOOEaOBdLDxrUl0o\n6E9C85uCY2yFW28Euo1F1kp9a0lNqnoVWhOcyQIDAQABAoIBAHTDlLx8P48yY1v5\nMg3XHbsG/oVQ1Ta8D+WsoIudM2nme0Z2PJvXyeQolGZGMu7NWznrOg5toYVuEHKu\niwFFDikuOig3IwVzs+ZVpFMjYtmyz7lZ+rJ0VvowcojZlCHoCpLwJ7ldYfE++via\nY2mJ5orVMDnzErO24hVqhtTvzlbbEfi3miVkdYnswiRB+4AdfWJ4nUS0yjiz0Ils\nQNFCxrqqcaqfIoWUIOuCRToep7c7bshXXj23lO6RNYXjfinQ66LM440Kz6Vfq7YD\nVOCnuuzPfjBXBEhqXrjFWb9vcnDoGgWmVCPKyVaX/RYsexcscbw/FI+SN+j4/Eis\nWyvJYAECgYEA/bBVOd1RdWkElGRIjI2VnuFQbJzZtGrr2iAOJ5GTqQNQoxN0Q1mc\n+k9//Roj90LZP3cGjNGMnDD050IAiL6HBLOsH+nmBjknVYDx6KNX04Qx1dCBU4sr\nfweNIoBzOngaYDr2MW9B+LoO6+AAg9oBMS/6q1JQC9WhWdDcdGnv5ckCgYEAxgW1\nCtL5lqMqLRKVIobeBqrbk7EEYTHR3RBYe4UwBWS8sWHnK52WonTrcAKz0mT/Qvly\nd9mCH8gajwXslFhr0oYwNdMhXj1Xbz+dmwcxJBaKpCugceaAbppASDHxpyb5PmPk\nhqZbHVALZS9ATWxEaOYHHCOq/K8zRmyOtup9fwECgYEA0/vDy513Uv/VNtZxlRk+\nXEv0cz3B4TOzL0q36TKJS6KcgBhif2Djfnpt/aYZRaVTeefQzlzP/TgffRq27Y0f\nr7sfam99qdeaWaVs/zN20SVWq08WsiPJzdUWhy0EnGf9ykmarYZFfzyh+VHtvD+v\n3VU1RnF6t9jkgfO4M4LOVpkCgYBdrHF6D1dWNRVMgQ/fZ2E8UjLQ6NojiEe4hXT1\n6YlP49mHJ8MNVNzfOOPu9rhN/Mzrx8iofVIa6rP1mF3TxwgqbKP9skSEqgvkanmJ\nzpsmcStggerCNsHmkD18aXPhGISrSOHf+G39rdHWKcYHWgH6hWYzn4PRSKe+1rek\nO8TmAQKBgQCf1r/wStjny0wYAgBFT4UNiRKSUP9DZZGGHQDYf5RmLLIt78Th57v3\nb1KzjJtQ8Pve4RjKNf1w/n1tS87Yl1Te4Jdngd3XVylgYpMBbxxozjpAv+svDgau\nVSA4vUgXsx2ORyJEI6amZTsmb5xPFh4m1M/JWdnQZaFCEAY5Enft6w==\n-----END RSA PRIVATE KEY-----\n", "user_id": "e455fde1790d463897b8e4a25bd88703", "name": "tempest-TestServerBasicOps-723183342", "fingerprint": "3c:8e:f5:b9:97:77:95:78:42:d5:4f:b8:1d:75:41:e3"}}
2017-02-10 07:25:30,893 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:8774/v2.1/os-security-groups 0.190s
2017-02-10 07:25:30,893 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"security_group": {"description": "tempest-TestServerBasicOps-1659809093 description", "name": "tempest-TestServerBasicOps-1659809093"}}
    Response - Headers: {u'x-compute-request-id': 'req-8e682106-526a-42e1-b328-b243c3001cc4', u'content-length': '245', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:30 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-security-groups', 'status': '200', u'content-type': 'application/json'}
        Body: {"security_group": {"rules": [], "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "id": "0df7e341-21e1-494f-861b-33a515a0df77", "name": "tempest-TestServerBasicOps-1659809093", "description": "tempest-TestServerBasicOps-1659809093 description"}}
2017-02-10 07:25:31,178 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:8774/v2.1/os-security-group-rules 0.282s
2017-02-10 07:25:31,179 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"security_group_rule": {"from_port": 22, "cidr": "0.0.0.0/0", "to_port": 22, "ip_protocol": "tcp", "parent_group_id": "0df7e341-21e1-494f-861b-33a515a0df77"}}
    Response - Headers: {u'x-compute-request-id': 'req-05cc9adf-d345-4762-80ca-0966685aeb23', u'content-length': '232', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:31 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-security-group-rules', 'status': '200', u'content-type': 'application/json'}
        Body: {"security_group_rule": {"from_port": 22, "group": {}, "ip_protocol": "tcp", "to_port": 22, "parent_group_id": "0df7e341-21e1-494f-861b-33a515a0df77", "ip_range": {"cidr": "0.0.0.0/0"}, "id": "f3ad211d-e998-4c77-9a2b-f33b9629f36f"}}
2017-02-10 07:25:31,484 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:8774/v2.1/os-security-group-rules 0.304s
2017-02-10 07:25:31,485 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"security_group_rule": {"from_port": -1, "cidr": "0.0.0.0/0", "to_port": -1, "ip_protocol": "icmp", "parent_group_id": "0df7e341-21e1-494f-861b-33a515a0df77"}}
    Response - Headers: {u'x-compute-request-id': 'req-cce4703e-9d2a-4ee3-933d-cd82ef631e5e', u'content-length': '233', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:31 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-security-group-rules', 'status': '200', u'content-type': 'application/json'}
        Body: {"security_group_rule": {"from_port": -1, "group": {}, "ip_protocol": "icmp", "to_port": -1, "parent_group_id": "0df7e341-21e1-494f-861b-33a515a0df77", "ip_range": {"cidr": "0.0.0.0/0"}, "id": "04d65e41-7dab-4360-863a-7aa3061b9549"}}
2017-02-10 07:25:31,527 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:5000/v2.0/tokens
2017-02-10 07:25:31,527 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: <omitted>
    Response - Headers: {u'x-openstack-request-id': 'req-b5d12671-623e-496b-9011-33d4e504127b', u'connection': 'close', u'server': 'Apache/2.4.18 (Ubuntu)', u'vary': 'X-Auth-Token', u'content-length': '2684', u'date': 'Fri, 10 Feb 2017 07:25:31 GMT', 'content-location': 'http://10.25.120.110:5000/v2.0/tokens', 'status': '200', u'content-type': 'application/json'}
        Body: {"access": {"token": {"issued_at": "2017-02-10T07:25:31.000000Z", "expires": "2017-02-10T08:25:31.000000Z", "id": "gAAAAABYnWrrLxUTHmgLT0lix0jowbH7d9T3QLLvbMox7XfEiy9tWgqCA2VvjwOiSLKwdhs8R1HUYoG4lKTrOAXolRzFukZSZSvl9D7O8HrR5oTK4jmHkiYd8YOKNWmgKVzt9-Ror6doIJNFD7p9zQ_0Ql2CBIAr3H73DA6-KTYxIGNomsO1Jqs", "tenant": {"description": "tempest-TestServerBasicOps-1649325349-desc", "enabled": true, "id": "2a17bbe4ffa04c0da3bb82425630103d", "name": "tempest-TestServerBasicOps-1649325349"}, "audit_ids": ["tVpWkp11RTaPwFU6Drrf4A"]}, "serviceCatalog": [{"endpoints": [{"adminURL": "http://10.25.120.110:8774/v2.1", "region": "RegionOne", "id": "5fb1fe7ea7714c04affa74df2a272b27", "internalURL": "http://10.25.120.110:8774/v2.1", "publicURL": "http://10.25.120.110:8774/v2.1"}], "endpoints_links": [], "type": "compute", "name": "nova"}, {"endpoints": [{"adminURL": "http://10.25.120.110:9696/", "region": "RegionOne", "id": "a8fd4226b5c247d59cde45e89971e44b", "internalURL": "http://10.25.120.110:9696/", "publicURL": "http://10.25.120.110:9696/"}], "endpoints_links": [], "type": "network", "name": "neutron"}, {"endpoints": [{"adminURL": "http://10.25.120.110:9292", "region": "RegionOne", "id": "2292470656fc48e5beaace3eb9be39da", "internalURL": "http://10.25.120.110:9292", "publicURL": "http://10.25.120.110:9292"}], "endpoints_links": [], "type": "image", "name": "glance"}, {"endpoints": [{"adminURL": "http://10.25.120.110:8774/v2/2a17bbe4ffa04c0da3bb82425630103d", "region": "RegionOne", "id": "71850fc2bac34038ba9ae8fd16c18920", "internalURL": "http://10.25.120.110:8774/v2/2a17bbe4ffa04c0da3bb82425630103d", "publicURL": "http://10.25.120.110:8774/v2/2a17bbe4ffa04c0da3bb82425630103d"}], "endpoints_links": [], "type": "compute_legacy", "name": "nova_legacy"}, {"endpoints": [{"adminURL": "http://10.25.120.110/placement", "region": "RegionOne", "id": "350c0c33e07a451a8d1998ba0016e6a0", "internalURL": "http://10.25.120.110/placement", "publicURL": "http://10.25.120.110/placement"}], "endpoints_links": [], "type": "placement", "name": "placement"}, {"endpoints": [{"adminURL": "http://10.25.120.110/identity_admin", "region": "RegionOne", "id": "2e46e005e2a54739ad0d308fb4cc2843", "internalURL": "http://10.25.120.110/identity", "publicURL": "http://10.25.120.110/identity"}], "endpoints_links": [], "type": "identity", "name": "keystone"}], "user": {"username": "tempest-TestServerBasicOps-1649325349", "roles_links": [], "id": "e455fde1790d463897b8e4a25bd88703", "roles": [{"name": "Member"}, {"name": "_member_"}], "name": "tempest-TestServerBasicOps-1649325349"}, "metadata": {"is_admin": 0, "roles": ["05a53f01de8a425497562792a65f1925", "9fe2ff9ee4384b1894a90878d3e92bab"]}}}
2017-02-10 07:25:31,528 2095 INFO [tempest.common.fixed_network] (TestServerBasicOps:test_server_basic_ops) Found network {u'tenant_id': u'2a17bbe4ffa04c0da3bb82425630103d', u'qos_policy_id': None, u'provider:network_type': u'midonet', u'provider:segmentation_id': None, u'provider:physical_network': None, u'shared': False, u'description': u'', u'updated_at': u'2017-02-10T07:25:27Z', u'availability_zones': [], u'revision_number': 3, u'availability_zone_hints': [], u'admin_state_up': True, u'name': u'tempest-TestServerBasicOps-352492870-network', u'created_at': u'2017-02-10T07:25:27Z', u'router:external': False, u'id': u'1fb3134d-f46b-4305-b74f-490d32a7a0ff', u'subnets': [], u'status': u'ACTIVE', u'tags': [], u'ipv6_address_scope': None, u'mtu': 0, u'port_security_enabled': True, u'project_id': u'2a17bbe4ffa04c0da3bb82425630103d', u'ipv4_address_scope': None} available for tenant
2017-02-10 07:25:32,409 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 202 POST http://10.25.120.110:8774/v2.1/servers 0.880s
2017-02-10 07:25:32,409 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"server": {"metadata": {"meta2": "data2", "meta1": "data1", "metaN": "dataN"}, "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "name": "tempest-TestServerBasicOps-server-1686170910", "imageRef": "48c2ed07-af07-485a-b363-f4e6c34c7478", "config_drive": true, "networks": [{"uuid": "1fb3134d-f46b-4305-b74f-490d32a7a0ff"}], "flavorRef": "42", "key_name": "tempest-TestServerBasicOps-723183342"}}
    Response - Headers: {u'x-compute-request-id': 'req-20a575eb-0a8c-4b6d-a8b6-6ab33c99dcea', u'content-length': '408', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:32 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers', 'status': '202', u'content-type': 'application/json', u'location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da'}
        Body: {"server": {"security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "OS-DCF:diskConfig": "MANUAL", "id": "52429377-1568-4b31-a632-aa6e1df157da", "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "adminPass": "WjwLNQjj7h4K"}}
2017-02-10 07:25:32,559 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.148s
2017-02-10 07:25:32,559 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-38ec23ca-06eb-47df-a4b0-3a36e9c86161', u'content-length': '1296', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:32 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "scheduling", "addresses": {}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:32Z", "hostId": "", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:33,827 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.261s
2017-02-10 07:25:33,827 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-39293337-9983-4a58-99b9-3fe8805364c2', u'content-length': '1354', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:33 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:33Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:33,833 2095 INFO [tempest.common.waiters] State transition "BUILD/scheduling" ==> "BUILD/spawning" after 1 second wait
2017-02-10 07:25:35,237 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.403s
2017-02-10 07:25:35,237 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-50aec0eb-8a7b-430b-8504-690f0092e050', u'content-length': '1426', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:35 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:33Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:36,581 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.337s
2017-02-10 07:25:36,582 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-c3bbfe16-4600-4d38-aaef-371a5f331982', u'content-length': '1426', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:36 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "spawning", "addresses": {}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "building", "OS-SRV-USG:launched_at": null, "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:33Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:37,919 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.331s
2017-02-10 07:25:37,919 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-5e727a30-4a07-49b2-90c4-b3b6d1fffa33', u'content-length': '1603', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:37 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"tempest-TestServerBasicOps-352492870-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:05:d0:78", "version": 4, "addr": "10.1.0.9", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-02-10T07:25:37.000000", "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:37Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:37,924 2095 INFO [tempest.common.waiters] State transition "BUILD/spawning" ==> "ACTIVE/None" after 5 second wait
2017-02-10 07:25:38,384 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.459s
2017-02-10 07:25:38,384 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-bae9e783-6861-48a6-8986-77fdc85083cb', u'content-length': '1603', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:38 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": null, "addresses": {"tempest-TestServerBasicOps-352492870-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:05:d0:78", "version": 4, "addr": "10.1.0.9", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-02-10T07:25:37.000000", "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:37Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:39,998 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 200 POST http://10.25.120.110:8774/v2.1/os-floating-ips 1.608s
2017-02-10 07:25:39,998 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"pool": null}
    Response - Headers: {u'x-compute-request-id': 'req-8ab64815-5ff5-46b7-b41d-9daadeecf13b', u'content-length': '141', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:39 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-floating-ips', 'status': '200', u'content-type': 'application/json'}
        Body: {"floating_ip": {"instance_id": null, "ip": "172.24.5.10", "fixed_ip": null, "id": "f6ad0618-dcee-4465-bff9-c876f1e40a96", "pool": "public"}}
2017-02-10 07:25:41,189 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:test_server_basic_ops): 202 POST http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da/action 1.188s
2017-02-10 07:25:41,189 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: {"addFloatingIp": {"address": "172.24.5.10"}}
    Response - Headers: {u'x-compute-request-id': 'req-a7c01132-c629-499e-bcb7-05c3734863fa', u'content-length': '0', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:41 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da/action', 'status': '202', u'content-type': 'text/html; charset=UTF-8'}
        Body:
2017-02-10 07:25:41,189 2095 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.10:22' as 'cirros' with public key authentication
2017-02-10 07:25:48,241 2095 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-02-10 07:25:48,393 2095 INFO [paramiko.transport] Authentication (publickey) successful!
2017-02-10 07:25:48,394 2095 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.10 successfully created
2017-02-10 07:25:48,508 2095 DEBUG [tempest.common.utils.linux.remote_client] Remote command: set -eu -o pipefail; PATH=$PATH:/sbin; blkid | grep -i config-2
2017-02-10 07:25:48,508 2095 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.10:22' as 'cirros' with public key authentication
2017-02-10 07:25:48,519 2095 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-02-10 07:25:48,711 2095 INFO [paramiko.transport] Authentication (publickey) successful!
2017-02-10 07:25:48,742 2095 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.10 successfully created
2017-02-10 07:25:48,865 2095 DEBUG [tempest.common.utils.linux.remote_client] Remote command: set -eu -o pipefail; PATH=$PATH:/sbin; sudo mount /dev/sr0 /mnt
2017-02-10 07:25:48,865 2095 INFO [tempest.lib.common.ssh] Creating ssh connection to '172.24.5.10:22' as 'cirros' with public key authentication
2017-02-10 07:25:48,880 2095 INFO [paramiko.transport] Connected (version 2.0, client dropbear_2012.55)
2017-02-10 07:25:49,050 2095 INFO [paramiko.transport] Authentication (publickey) successful!
2017-02-10 07:25:49,068 2095 INFO [tempest.lib.common.ssh] ssh connection to cirros@172.24.5.10 successfully created
2017-02-10 07:25:51,792 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.25.120.110:8774/v2.1/os-floating-ips/f6ad0618-dcee-4465-bff9-c876f1e40a96 2.183s
2017-02-10 07:25:51,793 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-71b2c055-d8c7-44c0-9306-70555fc69be4', u'content-length': '0', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:51 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-floating-ips/f6ad0618-dcee-4465-bff9-c876f1e40a96', 'status': '202', u'content-type': 'application/json'}
        Body:
2017-02-10 07:25:51,965 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 204 DELETE http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.172s
2017-02-10 07:25:51,966 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-a8053f51-7602-4388-96a6-36afacededbf', u'content-length': '0', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:51 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '204', u'content-type': 'application/json'}
        Body:
2017-02-10 07:25:52,327 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.361s
2017-02-10 07:25:52,328 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-ee6a822a-9768-43dc-8241-7d7b32e4c216', u'content-length': '1609', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:52 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "deleting", "addresses": {"tempest-TestServerBasicOps-352492870-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:05:d0:78", "version": 4, "addr": "10.1.0.9", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-02-10T07:25:37.000000", "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "security_groups": [{"name": "tempest-TestServerBasicOps-1659809093"}], "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:52Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:53,567 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 200 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.227s
2017-02-10 07:25:53,568 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-70c1910d-90aa-4fbc-b002-c75a816ff2f2', u'content-length': '1537', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:53 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '200', u'content-type': 'application/json'}
        Body: {"server": {"OS-EXT-STS:task_state": "deleting", "addresses": {"tempest-TestServerBasicOps-352492870-network": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:05:d0:78", "version": 4, "addr": "10.1.0.9", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "self"}, {"href": "http://10.25.120.110:8774/servers/52429377-1568-4b31-a632-aa6e1df157da", "rel": "bookmark"}], "image": {"id": "48c2ed07-af07-485a-b363-f4e6c34c7478", "links": [{"href": "http://10.25.120.110:8774/images/48c2ed07-af07-485a-b363-f4e6c34c7478", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-SRV-USG:launched_at": "2017-02-10T07:25:37.000000", "flavor": {"id": "42", "links": [{"href": "http://10.25.120.110:8774/flavors/42", "rel": "bookmark"}]}, "id": "52429377-1568-4b31-a632-aa6e1df157da", "user_id": "e455fde1790d463897b8e4a25bd88703", "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-02-10T07:25:53Z", "hostId": "d3738906414743e9cbc85a6583a0340abb1b481b085049ae944aba2c", "OS-SRV-USG:terminated_at": null, "key_name": "tempest-TestServerBasicOps-723183342", "name": "tempest-TestServerBasicOps-server-1686170910", "created": "2017-02-10T07:25:32Z", "tenant_id": "2a17bbe4ffa04c0da3bb82425630103d", "os-extended-volumes:volumes_attached": [], "config_drive": "True"}}
2017-02-10 07:25:54,661 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 404 GET http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da 0.086s
2017-02-10 07:25:54,661 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-0157fab1-f6d5-455b-b391-30e85ce968ae', u'content-length': '111', u'openstack-api-version': 'compute 2.1', u'content-type': 'application/json; charset=UTF-8', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:54 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/servers/52429377-1568-4b31-a632-aa6e1df157da', 'status': '404'}
        Body: {"itemNotFound": {"message": "Instance 52429377-1568-4b31-a632-aa6e1df157da could not be found.", "code": 404}}
2017-02-10 07:25:54,940 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.25.120.110:8774/v2.1/os-security-groups/0df7e341-21e1-494f-861b-33a515a0df77 0.279s
2017-02-10 07:25:54,940 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-dc5a0d18-66f3-4263-bb10-712aa9f7fb34', u'content-length': '0', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:54 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-security-groups/0df7e341-21e1-494f-861b-33a515a0df77', 'status': '202', u'content-type': 'application/json'}
        Body:
2017-02-10 07:25:54,984 2095 INFO [tempest.lib.common.rest_client] Request (TestServerBasicOps:_run_cleanups): 202 DELETE http://10.25.120.110:8774/v2.1/os-keypairs/tempest-TestServerBasicOps-723183342 0.043s
2017-02-10 07:25:54,984 2095 DEBUG [tempest.lib.common.rest_client] Request - Headers: {'X-Auth-Token': '<omitted>', 'Accept': 'application/json', 'Content-Type': 'application/json'}
        Body: None
    Response - Headers: {u'x-compute-request-id': 'req-45e872b2-a62d-42f2-900d-06b5a1b50fac', u'content-length': '0', u'openstack-api-version': 'compute 2.1', u'vary': 'X-OpenStack-Nova-API-Version', u'connection': 'close', u'date': 'Fri, 10 Feb 2017 07:25:54 GMT', u'x-openstack-nova-api-version': '2.1', 'content-location': 'http://10.25.120.110:8774/v2.1/os-keypairs/tempest-TestServerBasicOps-723183342', 'status': '202', u'content-type': 'application/json'}
        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 145, in test_server_basic_ops
    self.verify_metadata_on_config_drive()
  File "tempest/scenario/test_server_basic_ops.py", line 104, in verify_metadata_on_config_drive
    self._mount_config_drive()
  File "tempest/scenario/test_server_basic_ops.py", line 96, in _mount_config_drive
    self.ssh_client.exec_command('sudo mount %s /mnt' % dev_name)
  File "tempest/common/utils/linux/remote_client.py", line 35, in wrapper
    return function(self, *args, **kwargs)
  File "tempest/common/utils/linux/remote_client.py", line 91, in exec_command
    return self.ssh_client.exec_command(cmd)
  File "tempest/lib/common/ssh.py", line 201, in exec_command
    stderr=err_data, stdout=out_data)
tempest.lib.exceptions.SSHExecCommandFailed: Command 'set -eu -o pipefail; PATH=$PATH:/sbin; sudo mount /dev/sr0 /mnt', exit status: 255, stderr:
mount: mounting /dev/sr0 on /mnt failed: Device or resource busy

stdout:

tags: added: upstream-gate
Revision history for this message
Andrea Frittoli (andrea-frittoli) wrote :
Changed in tempest:
importance: Undecided → Medium
status: New → Triaged
importance: Medium → High
Revision history for this message
Andrea Frittoli (andrea-frittoli) wrote :

Setting as high since the incidence in logstash is high enough and it seems a good target for at least and E-R query.

Revision history for this message
Andrea Frittoli (andrea-frittoli) wrote :
Revision history for this message
Matt Riedemann (mriedem) wrote :

This bug report hasn't been updated in over 2 years and isn't hitting in e-r anymore so I'm marking it invalid:

http://status.openstack.org/elastic-recheck/#1663529

someone can re-open if it comes back.

Changed in tempest:
status: Triaged → Invalid
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Duplicates of this bug

Other bug subscribers

Remote bug watches

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