source('F:/works.sinorail/operationlog/code/function.R')
Plog<-read_lines("G:/ztx/T2works/log/192.168.17.23/nova/nova-compute.log")
Plog<-paste.log(Plog)
Plog %>% grep("71e57a77-729f-4613-9e97-b4f63f9028d7",.,value = T)## [1] "2019-03-11 15:04:47.690 2303 DEBUG oslo_concurrency.lockutils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] Lock \"71e57a77-729f-4613-9e97-b4f63f9028d7\" acquired by \"nova.compute.manager._locked_do_build_and_run_instance\" :: waited 0.000s inner /usr/lib/python2.7/site-packages/oslo_concurrency/lockutils.py:253"
## [2] "2019-03-11 15:04:47.701 2303 INFO nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Starting instance..."
## [3] "2019-03-11 15:04:47.826 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Attempting claim: memory 64 MB, disk 1 GB"
## [4] "2019-03-11 15:04:47.826 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Total memory: 131043 MB, used: 98816.00 MB"
## [5] "2019-03-11 15:04:47.826 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] memory limit: 131043.00 MB, free: 32227.00 MB"
## [6] "2019-03-11 15:04:47.827 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Total disk: 60470 GB, used: 940.00 GB"
## [7] "2019-03-11 15:04:47.827 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] disk limit not specified, defaulting to unlimited"
## [8] "2019-03-11 15:04:47.838 2303 INFO nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Claim successful"
## [9] "2019-03-11 15:04:48.195 2303 DEBUG nova.compute.manager [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Allocating IP information in the background. _allocate_network_async /usr/lib/python2.7/site-packages/nova/compute/manager.py:1578"
## [10] "2019-03-11 15:04:48.207 2303 DEBUG nova.network.neutronv2.api [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] allocate_for_instance() allocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:645"
## [11] "2019-03-11 15:04:48.289 2303 DEBUG keystoneclient.session [-] REQ: curl -g -i -X POST http://192.168.17.102:9696/v2.0/ports.json -H \"User-Agent: python-neutronclient\" -H \"Content-Type: application/json\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}b72e4de93e67b6ada54eeb71b1e03c8e1729126e\" -d '{\"port\": {\"binding:host_id\": \"compute7\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"device_owner\": \"compute:xkjtest:default_cluster\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\"}}' _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [12] "2019-03-11 15:04:48.445 2303 INFO nova.virt.libvirt.driver [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Creating image"
## [13] "2019-03-11 15:04:48.477 2303 DEBUG nova.virt.libvirt.storage.rbd_utils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] rbd image 71e57a77-729f-4613-9e97-b4f63f9028d7_disk does not exist __init__ /usr/lib/python2.7/site-packages/nova/virt/libvirt/storage/rbd_utils.py:67"
## [14] "2019-03-11 15:04:48.510 2303 DEBUG nova.virt.libvirt.storage.rbd_utils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] rbd image 71e57a77-729f-4613-9e97-b4f63f9028d7_disk does not exist __init__ /usr/lib/python2.7/site-packages/nova/virt/libvirt/storage/rbd_utils.py:67"
## [15] "2019-03-11 15:04:48.810 2303 DEBUG nova.virt.libvirt.storage.rbd_utils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] cloning images/ad5af451-9bd2-47a8-8cde-50934b0d3bdf@snap to None/71e57a77-729f-4613-9e97-b4f63f9028d7_disk clone /usr/lib/python2.7/site-packages/nova/virt/libvirt/storage/rbd_utils.py:221"
## [16] "2019-03-11 15:04:49.247 2303 DEBUG nova.virt.libvirt.storage.rbd_utils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] resizing rbd image 71e57a77-729f-4613-9e97-b4f63f9028d7_disk to 1073741824 resize /usr/lib/python2.7/site-packages/nova/virt/libvirt/storage/rbd_utils.py:245"
## [17] "2019-03-11 15:04:49.350 2303 INFO nova.virt.libvirt.driver [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Using config drive"
## [18] "2019-03-11 15:04:49.366 2303 DEBUG keystoneclient.session [-] RESP: [201] date: Mon, 11 Mar 2019 07:04:48 GMT connection: close content-type: application/json; charset=UTF-8 content-length: 782 x-openstack-request-id: req-3a8b1e40-c6ff-427b-b279-168427beeefd RESP BODY: {\"port\": {\"status\": \"DOWN\", \"binding:host_id\": \"compute7\", \"allowed_address_pairs\": [], \"extra_dhcp_opts\": [], \"device_owner\": \"compute:xkjtest:default_cluster\", \"port_security_enabled\": true, \"binding:profile\": {}, \"qos_policy_id\": null, \"fixed_ips\": [{\"subnet_id\": \"3468c599-c78b-4dea-a413-ab086b1f69e1\", \"ip_address\": \"10.1.2.5\"}], \"id\": \"beefc468-9316-4264-80bd-fef4c13899ca\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\", \"name\": \"\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"dns_name\": \"\", \"binding:vif_details\": {}, \"binding:vnic_type\": \"normal\", \"binding:vif_type\": \"binding_failed\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"mac_address\": \"fa:16:3e:d4:91:be\"}} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:215"
## [19] "2019-03-11 15:04:49.386 2303 DEBUG keystoneclient.session [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] REQ: curl -g -i -X GET http://192.168.17.102:9696/v2.0/ports.json?device_id=71e57a77-729f-4613-9e97-b4f63f9028d7 -H \"User-Agent: python-neutronclient\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}b72e4de93e67b6ada54eeb71b1e03c8e1729126e\" _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [20] "2019-03-11 15:04:49.517 2303 DEBUG keystoneclient.session [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] RESP: [200] date: Mon, 11 Mar 2019 07:04:49 GMT connection: close content-type: application/json; charset=UTF-8 content-length: 903 x-openstack-request-id: req-c2422394-7839-46ae-89d5-3993590136c8 RESP BODY: {\"ports\": [{\"status\": \"DOWN\", \"binding:host_id\": \"compute7\", \"allowed_address_pairs\": [], \"extra_dhcp_opts\": [], \"dns_assignment\": [{\"hostname\": \"host-10-1-2-5\", \"ip_address\": \"10.1.2.5\", \"fqdn\": \"host-10-1-2-5.openstacklocal.\"}], \"device_owner\": \"compute:xkjtest:default_cluster\", \"port_security_enabled\": true, \"binding:profile\": {}, \"qos_policy_id\": null, \"fixed_ips\": [{\"subnet_id\": \"3468c599-c78b-4dea-a413-ab086b1f69e1\", \"ip_address\": \"10.1.2.5\"}], \"id\": \"beefc468-9316-4264-80bd-fef4c13899ca\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\", \"name\": \"\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"dns_name\": \"\", \"binding:vif_details\": {}, \"binding:vnic_type\": \"normal\", \"binding:vif_type\": \"binding_failed\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"mac_address\": \"fa:16:3e:d4:91:be\"}]} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:215"
## [21] "2019-03-11 15:04:49.727 2303 WARNING nova.compute.manager [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance failed network setup (attempt 1 of 4)"
## [22] "2019-03-11 15:04:50.729 2303 DEBUG nova.network.neutronv2.api [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] allocate_for_instance() allocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:645"
## [23] "2019-03-11 15:04:50.808 2303 DEBUG keystoneclient.session [-] REQ: curl -g -i -X POST http://192.168.17.102:9696/v2.0/ports.json -H \"User-Agent: python-neutronclient\" -H \"Content-Type: application/json\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}b72e4de93e67b6ada54eeb71b1e03c8e1729126e\" -d '{\"port\": {\"binding:host_id\": \"compute7\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"device_owner\": \"compute:xkjtest:default_cluster\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\"}}' _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [24] "2019-03-11 15:04:51.527 2303 DEBUG keystoneclient.session [-] RESP: [201] date: Mon, 11 Mar 2019 07:04:51 GMT connection: close content-type: application/json; charset=UTF-8 content-length: 782 x-openstack-request-id: req-871bf0e8-98dc-4e8a-bc63-4a2a52806c24 RESP BODY: {\"port\": {\"status\": \"DOWN\", \"binding:host_id\": \"compute7\", \"allowed_address_pairs\": [], \"extra_dhcp_opts\": [], \"device_owner\": \"compute:xkjtest:default_cluster\", \"port_security_enabled\": true, \"binding:profile\": {}, \"qos_policy_id\": null, \"fixed_ips\": [{\"subnet_id\": \"3468c599-c78b-4dea-a413-ab086b1f69e1\", \"ip_address\": \"10.1.2.6\"}], \"id\": \"9f963e5e-37e7-4cc3-908d-27e02fc9f1dc\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\", \"name\": \"\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"dns_name\": \"\", \"binding:vif_details\": {}, \"binding:vnic_type\": \"normal\", \"binding:vif_type\": \"binding_failed\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"mac_address\": \"fa:16:3e:85:98:af\"}} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:215"
## [25] "2019-03-11 15:04:51.942 2303 DEBUG nova.compute.manager [req-b56384e5-c6fd-4eae-a176-ba7d24f1f884 02d1dc3a1755454fb4f2a5ab0d058881 5c87c61d4d6e4a3f9197d54890f4e565 - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Received event network-vif-deleted-beefc468-9316-4264-80bd-fef4c13899ca external_instance_event /usr/lib/python2.7/site-packages/nova/compute/manager.py:6696"
## [26] "2019-03-11 15:04:51.972 2303 WARNING nova.compute.manager [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance failed network setup (attempt 2 of 4)"
## [27] "2019-03-11 15:04:53.974 2303 DEBUG nova.network.neutronv2.api [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] allocate_for_instance() allocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:645"
## [28] "2019-03-11 15:04:54.055 2303 DEBUG keystoneclient.session [-] REQ: curl -g -i -X POST http://192.168.17.102:9696/v2.0/ports.json -H \"User-Agent: python-neutronclient\" -H \"Content-Type: application/json\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}b72e4de93e67b6ada54eeb71b1e03c8e1729126e\" -d '{\"port\": {\"binding:host_id\": \"compute7\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"device_owner\": \"compute:xkjtest:default_cluster\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\"}}' _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [29] "2019-03-11 15:04:54.159 2303 DEBUG nova.compute.manager [req-8e9415ca-de50-4b37-8d5b-285645fd04ce 02d1dc3a1755454fb4f2a5ab0d058881 5c87c61d4d6e4a3f9197d54890f4e565 - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Received event network-vif-deleted-9f963e5e-37e7-4cc3-908d-27e02fc9f1dc external_instance_event /usr/lib/python2.7/site-packages/nova/compute/manager.py:6696"
## [30] "2019-03-11 15:04:54.532 2303 DEBUG keystoneclient.session [-] RESP: [201] date: Mon, 11 Mar 2019 07:04:54 GMT connection: close content-type: application/json; charset=UTF-8 content-length: 782 x-openstack-request-id: req-837f0075-c1b7-4fc4-91ae-a673ad6ab653 RESP BODY: {\"port\": {\"status\": \"DOWN\", \"binding:host_id\": \"compute7\", \"allowed_address_pairs\": [], \"extra_dhcp_opts\": [], \"device_owner\": \"compute:xkjtest:default_cluster\", \"port_security_enabled\": true, \"binding:profile\": {}, \"qos_policy_id\": null, \"fixed_ips\": [{\"subnet_id\": \"3468c599-c78b-4dea-a413-ab086b1f69e1\", \"ip_address\": \"10.1.2.7\"}], \"id\": \"80b4d3e7-238e-4dd8-a885-29fd80e0baef\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\", \"name\": \"\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"dns_name\": \"\", \"binding:vif_details\": {}, \"binding:vnic_type\": \"normal\", \"binding:vif_type\": \"binding_failed\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"mac_address\": \"fa:16:3e:fb:c6:f3\"}} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:215"
## [31] "2019-03-11 15:04:54.914 2303 WARNING nova.compute.manager [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance failed network setup (attempt 3 of 4)"
## [32] "2019-03-11 15:04:57.129 2303 DEBUG nova.compute.manager [req-b05f9ec6-6580-42b2-9997-3913d1ccc5c2 02d1dc3a1755454fb4f2a5ab0d058881 5c87c61d4d6e4a3f9197d54890f4e565 - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Received event network-vif-deleted-80b4d3e7-238e-4dd8-a885-29fd80e0baef external_instance_event /usr/lib/python2.7/site-packages/nova/compute/manager.py:6696"
## [33] "2019-03-11 15:04:58.915 2303 DEBUG nova.network.neutronv2.api [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] allocate_for_instance() allocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:645"
## [34] "2019-03-11 15:04:58.992 2303 DEBUG keystoneclient.session [-] REQ: curl -g -i -X POST http://192.168.17.102:9696/v2.0/ports.json -H \"User-Agent: python-neutronclient\" -H \"Content-Type: application/json\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}b72e4de93e67b6ada54eeb71b1e03c8e1729126e\" -d '{\"port\": {\"binding:host_id\": \"compute7\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"device_owner\": \"compute:xkjtest:default_cluster\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\"}}' _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [35] "2019-03-11 15:04:59.441 2303 DEBUG keystoneclient.session [-] RESP: [201] date: Mon, 11 Mar 2019 07:04:59 GMT connection: close content-type: application/json; charset=UTF-8 content-length: 782 x-openstack-request-id: req-6117681c-cd85-42b9-807e-b40060f35538 RESP BODY: {\"port\": {\"status\": \"DOWN\", \"binding:host_id\": \"compute7\", \"allowed_address_pairs\": [], \"extra_dhcp_opts\": [], \"device_owner\": \"compute:xkjtest:default_cluster\", \"port_security_enabled\": true, \"binding:profile\": {}, \"qos_policy_id\": null, \"fixed_ips\": [{\"subnet_id\": \"3468c599-c78b-4dea-a413-ab086b1f69e1\", \"ip_address\": \"10.1.2.8\"}], \"id\": \"0468e30a-15cb-47f5-b8f6-3be344d053b9\", \"security_groups\": [\"737f063f-7aa9-4041-8129-2df5f1634525\"], \"device_id\": \"71e57a77-729f-4613-9e97-b4f63f9028d7\", \"name\": \"\", \"admin_state_up\": true, \"network_id\": \"4436194a-71da-431c-88b4-17b29b701355\", \"dns_name\": \"\", \"binding:vif_details\": {}, \"binding:vnic_type\": \"normal\", \"binding:vif_type\": \"binding_failed\", \"tenant_id\": \"c5a56983ad334846acf1b89d9fd01b4d\", \"mac_address\": \"fa:16:3e:34:2d:e4\"}} _http_log_response /usr/lib/python2.7/site-packages/keystoneclient/session.py:215"
## [36] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance failed to spawn"
## [37] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Traceback (most recent call last):"
## [38] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/compute/manager.py\", line 2188, in _build_resources"
## [39] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] yield resources"
## [40] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/compute/manager.py\", line 2041, in _build_and_run_instance"
## [41] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] block_device_info=block_device_info)"
## [42] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py\", line 2657, in spawn"
## [43] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] admin_pass=admin_password)"
## [44] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/virt/libvirt/driver.py\", line 3168, in _create_image"
## [45] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] content=files, extra_md=extra_md, network_info=network_info)"
## [46] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/api/metadata/base.py\", line 160, in __init__"
## [47] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] self.network_metadata = netutils.get_network_metadata(network_info)"
## [48] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/virt/netutils.py\", line 209, in get_network_metadata"
## [49] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] if not network_info:"
## [50] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/model.py\", line 514, in __len__"
## [51] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] return self._sync_wrapper(fn, *args, **kwargs)"
## [52] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/model.py\", line 501, in _sync_wrapper"
## [53] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] self.wait()"
## [54] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/model.py\", line 533, in wait"
## [55] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] self[:] = self._gt.wait()"
## [56] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/eventlet/greenthread.py\", line 175, in wait"
## [57] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] return self._exit_event.wait()"
## [58] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/eventlet/event.py\", line 121, in wait"
## [59] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] return hubs.get_hub().switch()"
## [60] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/eventlet/hubs/hub.py\", line 294, in switch"
## [61] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] return self.greenlet.switch()"
## [62] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/eventlet/greenthread.py\", line 214, in main"
## [63] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] result = function(*args, **kwargs)"
## [64] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/compute/manager.py\", line 1613, in _allocate_network_async"
## [65] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] six.reraise(*exc_info)"
## [66] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/compute/manager.py\", line 1596, in _allocate_network_async"
## [67] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] bind_host_id=bind_host_id)"
## [68] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py\", line 749, in allocate_for_instance"
## [69] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] self._delete_ports(neutron, instance, created_port_ids)"
## [70] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/oslo_utils/excutils.py\", line 195, in __exit__"
## [71] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] six.reraise(self.type_, self.value, self.tb)"
## [72] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py\", line 741, in allocate_for_instance"
## [73] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] security_group_ids, available_macs, dhcp_opts)"
## [74] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] File \"/usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py\", line 361, in _create_port"
## [75] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] raise exception.PortBindingFailed(port_id=port_id)"
## [76] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] PortBindingFailed: Binding failed for port 0468e30a-15cb-47f5-b8f6-3be344d053b9, please check neutron logs for more information."
## [77] "2019-03-11 15:04:59.843 2303 ERROR nova.compute.manager [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] "
## [78] "2019-03-11 15:04:59.873 2303 INFO nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Terminating instance"
## [79] "2019-03-11 15:04:59.882 2303 INFO nova.virt.libvirt.driver [-] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] During wait destroy, instance disappeared."
## [80] "2019-03-11 15:04:59.982 2303 DEBUG oslo_concurrency.processutils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] Running cmd (subprocess): mv /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7 /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7_del execute /usr/lib/python2.7/site-packages/oslo_concurrency/processutils.py:297"
## [81] "2019-03-11 15:05:00.004 2303 DEBUG oslo_concurrency.processutils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] CMD \"mv /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7 /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7_del\" returned: 0 in 0.022s execute /usr/lib/python2.7/site-packages/oslo_concurrency/processutils.py:327"
## [82] "2019-03-11 15:05:00.005 2303 INFO nova.virt.libvirt.driver [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Deleting instance files /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7_del"
## [83] "2019-03-11 15:05:00.005 2303 INFO nova.virt.libvirt.driver [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Deletion of /var/lib/nova/instances/71e57a77-729f-4613-9e97-b4f63f9028d7_del complete"
## [84] "2019-03-11 15:05:00.143 2303 DEBUG nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Deallocating network for instance _deallocate_network /usr/lib/python2.7/site-packages/nova/compute/manager.py:1840"
## [85] "2019-03-11 15:05:00.144 2303 DEBUG nova.network.neutronv2.api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] deallocate_for_instance() deallocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:837"
## [86] "2019-03-11 15:05:00.145 2303 DEBUG keystoneclient.session [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] REQ: curl -g -i -X GET http://192.168.17.102:9696/v2.0/ports.json?device_id=71e57a77-729f-4613-9e97-b4f63f9028d7 -H \"User-Agent: python-neutronclient\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}e8e0d4b9e85d7808088da406c8443e1fededdf65\" _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [87] "2019-03-11 15:05:00.180 2303 DEBUG nova.network.neutronv2.api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance cache missing network info. _get_preexisting_port_ids /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:1702"
## [88] "2019-03-11 15:05:00.182 2303 DEBUG nova.network.base_api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Updating instance_info_cache with network_info: [] update_instance_cache_with_nw_info /usr/lib/python2.7/site-packages/nova/network/base_api.py:43"
## [89] "2019-03-11 15:05:00.194 2303 DEBUG nova.compute.claims [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Aborting claim: [Claim: 64 MB memory, 1 GB disk] abort /usr/lib/python2.7/site-packages/nova/compute/claims.py:112"
## [90] "2019-03-11 15:05:00.242 2303 DEBUG nova.compute.utils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Binding failed for port 0468e30a-15cb-47f5-b8f6-3be344d053b9, please check neutron logs for more information. notify_about_instance_usage /usr/lib/python2.7/site-packages/nova/compute/utils.py:284"
## [91] "2019-03-11 15:05:00.247 2303 DEBUG nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Retry info not present, will not reschedule _do_build_and_run_instance /usr/lib/python2.7/site-packages/nova/compute/manager.py:1944"
## [92] "2019-03-11 15:05:00.247 2303 DEBUG nova.compute.manager [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Deallocating network for instance _deallocate_network /usr/lib/python2.7/site-packages/nova/compute/manager.py:1840"
## [93] "2019-03-11 15:05:00.247 2303 DEBUG nova.network.neutronv2.api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] deallocate_for_instance() deallocate_for_instance /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:837"
## [94] "2019-03-11 15:05:00.248 2303 DEBUG keystoneclient.session [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] REQ: curl -g -i -X GET http://192.168.17.102:9696/v2.0/ports.json?device_id=71e57a77-729f-4613-9e97-b4f63f9028d7 -H \"User-Agent: python-neutronclient\" -H \"Accept: application/json\" -H \"X-Auth-Token: {SHA1}e8e0d4b9e85d7808088da406c8443e1fededdf65\" _http_log_request /usr/lib/python2.7/site-packages/keystoneclient/session.py:198"
## [95] "2019-03-11 15:05:00.281 2303 DEBUG nova.network.neutronv2.api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Instance cache missing network info. _get_preexisting_port_ids /usr/lib/python2.7/site-packages/nova/network/neutronv2/api.py:1702"
## [96] "2019-03-11 15:05:00.283 2303 DEBUG nova.network.base_api [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] [instance: 71e57a77-729f-4613-9e97-b4f63f9028d7] Updating instance_info_cache with network_info: [] update_instance_cache_with_nw_info /usr/lib/python2.7/site-packages/nova/network/base_api.py:43"
## [97] "2019-03-11 15:05:00.561 2303 DEBUG oslo_concurrency.lockutils [req-afa41633-f929-46b5-918c-e4ce717e8c4f 24d2188097bd4ac1a1c9b3ce2198d892 c5a56983ad334846acf1b89d9fd01b4d - - -] Lock \"71e57a77-729f-4613-9e97-b4f63f9028d7\" released by \"nova.compute.manager._locked_do_build_and_run_instance\" :: held 12.871s inner /usr/lib/python2.7/site-packages/oslo_concurrency/lockutils.py:265"