← Back to team overview

yahoo-eng-team team mailing list archive

[Bug 1557748] [NEW] ERROR nova.compute.manager Instance failed to spawn

 

Public bug reported:

Hi,

I installed the version of the OpenStack Kilo, following this guide
(http://docs.openstack.org/kilo/install-
guide/install/apt/content/index.html)

I joined the OpenStack to the controller SDN Onos version 1.3.

I installed the plugin ml2 (networking-onos) for the integration ONOS.

The ext-net works with flat, while the demo-net with vxlan.

I shared the demo-net network with the admin user and network ext-net
with the user demo.

When I instantiate a VM with the demo-net for any user, the creation of
the VM is performed normally and it works perfectly.

When I instantiate a VM with network ext-net for any user, it appears an
error message.

I did a test assigning Floating IP for a VM with demo-net and it
received external IP and managed to communicate with hosts on the
Internet.

I am pasting below the neutron-server.log and
/etc/neutron/plugins/ml2/ml2_conf.ini.

neutron net-list
+--------------------------------------+----------+-------------------------------------------------------+
| id                                   | name     | subnets                                               |
+--------------------------------------+----------+-------------------------------------------------------+
| 040f1e75-afa2-4d35-9a80-5e2b57d2a731 | ext-net  | 87ee275c-fd8a-45a4-897c-3215f3e44671 200.XXX.XXX.0/24  |
| 8dc2922f-c233-4061-bcd2-f2ac5e962bf8 | demo-net | b672fe74-5604-46cd-8046-d6164a0e5d68 192.168.100.0/24 |
+--------------------------------------+----------+-------------------------------------------------------+

The command to create the VM with admin user on OpenStack:

nova boot --flavor m1.tiny --image cirros-0.3.4-x86_64 --nic net-
id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 --security-group default admin-
instance1

Following nova-compute.log's message:

2016-03-15 17:37:39.262 8901 INFO nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Starting instance...
2016-03-15 17:37:40.427 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Attempting claim: memory 512 MB, disk 1 GB
2016-03-15 17:37:40.429 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Total memory: 7980 MB, used: 1536.00 MB
2016-03-15 17:37:40.430 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] memory limit: 11970.00 MB, free: 10434.00 MB
2016-03-15 17:37:40.431 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Total disk: 450 GB, used: 20.00 GB
2016-03-15 17:37:40.432 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] disk limit not specified, defaulting to unlimited
2016-03-15 17:37:40.630 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Claim successful
2016-03-15 17:37:42.365 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
2016-03-15 17:37:56.739 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
2016-03-15 17:38:05.972 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Creating image
2016-03-15 17:38:06.530 8901 INFO nova.virt.disk.vfs.api [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Unable to import guestfs, falling back to VFSLocalFS
2016-03-15 17:38:10.721 8901 ERROR nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Instance failed to spawn
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Traceback (most recent call last):
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2473, in _build_resources
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     yield resources
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2345, in _build_and_run_instance
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     block_device_info=block_device_info)
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 2388, in spawn
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     write_to_disk=True)
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4189, in _get_guest_xml
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     context)
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4060, in _get_guest_config
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     flavor, virt_type)
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/vif.py", line 374, in get_config
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     _("Unexpected vif_type=%s") % vif_type)
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] NovaException: Unexpected vif_type=binding_failed
2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] 
2016-03-15 17:38:10.725 8901 INFO nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Terminating instance
2016-03-15 17:38:10.737 8901 INFO nova.virt.libvirt.driver [-] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] During wait destroy, instance disappeared.
2016-03-15 17:38:10.776 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Deleting instance files /var/lib/nova/instances/5a5e7552-82bc-47f8-b6dc-060d9c94dc77_del
2016-03-15 17:38:10.778 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Deletion of /var/lib/nova/instances/5a5e7552-82bc-47f8-b6dc-060d9c94dc77_del complete
2016-03-15 17:38:12.363 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
2016-03-15 17:38:21.984 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Auditing locally available compute resources for node compute1
2016-03-15 17:38:22.776 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Total usable vcpus: 4, total allocated vcpus: 2
2016-03-15 17:38:22.777 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Final resource view: name=compute1 phys_ram=7980MB used_ram=2048MB phys_disk=450GB used_disk=21GB total_vcpus=4 used_vcpus=2 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f61385b81d0>
2016-03-15 17:38:22.818 8901 INFO nova.scheduler.client.report [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Compute_service record updated for ('compute1', 'compute1')
2016-03-15 17:38:22.818 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Compute_service record updated for compute1:compute1
2016-03-15 17:39:23.464 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Auditing locally available compute resources for node compute1

Following neutron-server.log's message:

2016-03-15 17:37:14.352 6978 INFO neutron.wsgi [req-d2ada08f-e886-420c-a1e5-764df8707503 ] 10.0.0.31 - - [15/Mar/2016 17:37:14] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.034140
2016-03-15 17:37:20.209 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45632)
2016-03-15 17:37:20.615 6978 INFO neutron.wsgi [req-ed1c0203-d78f-456b-8a1c-4429afc072fc ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/networks.json?id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 HTTP/1.1" 200 600 0.112256
2016-03-15 17:37:20.763 6978 INFO neutron.wsgi [req-51cf845a-a372-4c23-8b90-24a30cbc3cca ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208 HTTP/1.1" 200 1628 0.025041
2016-03-15 17:37:20.802 6978 INFO neutron.wsgi [req-e7188982-ae94-48bd-8e61-00f0ca58ddbe ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/quotas/5a0fda70f39e4214813b10d38d527208.json HTTP/1.1" 200 407 0.035778
2016-03-15 17:37:33.776 6978 INFO neutron.wsgi [req-5715ae4c-38a8-43a8-a7f5-cbe44448b338 ] 10.0.0.11 - - [15/Mar/2016 17:37:33] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77 HTTP/1.1" 200 226 0.020209
2016-03-15 17:37:56.746 6978 INFO neutron.wsgi [req-3be95805-ba32-4ac9-bc71-8815426ab586 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/extensions.json HTTP/1.1" 200 5572 0.004779
2016-03-15 17:37:56.773 6978 INFO neutron.wsgi [req-1aafd4f0-49e9-4c3f-be46-b19bf6620d81 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/networks.json?id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 HTTP/1.1" 200 600 0.022951
2016-03-15 17:37:56.802 6978 INFO neutron.wsgi [req-134f3f3e-8bbf-45e1-8e03-d8880e7ee2d7 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/security-groups.json?tenant_id=5a0fda70f39e4214813b10d38d527208 HTTP/1.1" 200 2413 0.023847
2016-03-15 17:37:58.935 6978 INFO neutron.callbacks.manager [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Notify callbacks for port, after_create
2016-03-15 17:37:58.936 6978 INFO neutron.callbacks.manager [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Calling callback neutron.db.l3_dvrscheduler_db._notify_l3_agent_new_port
2016-03-15 17:37:58.983 6978 ERROR neutron.plugins.ml2.managers [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Failed to bind port e2cb725b-903d-4876-9b9c-06efb847bcfd on host compute1
2016-03-15 17:37:58.984 6978 ERROR neutron.plugins.ml2.managers [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Failed to bind port e2cb725b-903d-4876-9b9c-06efb847bcfd on host compute1
2016-03-15 17:38:00.068 6978 INFO neutron.wsgi [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "POST /v2.0/ports.json HTTP/1.1" 201 918 3.260751
2016-03-15 17:38:00.104 6978 INFO neutron.wsgi [req-98d22f40-da0f-4812-bd6a-eb17b6a6cc80 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77 HTTP/1.1" 200 916 0.029720
2016-03-15 17:38:00.116 6978 INFO neutron.wsgi [req-92c5180e-f41e-43cd-a373-9f7f5d22338d ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/floatingips.json?fixed_ip_address=200.131.22.107&port_id=e2cb725b-903d-4876-9b9c-06efb847bcfd HTTP/1.1" 200 232 0.007215
2016-03-15 17:38:00.132 6978 INFO neutron.wsgi [req-a127cf8d-4815-46a8-992d-66296b2ec6dd ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/subnets.json?id=87ee275c-fd8a-45a4-897c-3215f3e44671 HTTP/1.1" 200 686 0.014205
2016-03-15 17:38:00.154 6978 INFO neutron.wsgi [req-66c359cc-5198-4198-9472-ea5966fee596 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/ports.json?network_id=040f1e75-afa2-4d35-9a80-5e2b57d2a731&device_owner=network%3Adhcp HTTP/1.1" 200 226 0.019185
2016-03-15 17:38:14.536 6978 INFO neutron.wsgi [req-773954d2-1be4-42da-9d1a-f4732f71f41f ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 926 0.026399
2016-03-15 17:38:14.905 6978 INFO neutron.wsgi [req-2899a255-53f7-4cc6-a362-152aee92c3d4 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/networks.json?id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8 HTTP/1.1" 200 597 0.023312
2016-03-15 17:38:14.915 6978 INFO neutron.wsgi [req-73ca90dc-4ecd-4c6d-8a8e-b3ca232f0970 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/floatingips.json?fixed_ip_address=192.168.100.31&port_id=10fb1dbb-9fb0-4a25-82f9-3bb621a72f6a HTTP/1.1" 200 594 0.007326
2016-03-15 17:38:14.930 6978 INFO neutron.wsgi [req-452fac18-7144-46f2-a13b-edf4dbf7e4c3 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/subnets.json?id=b672fe74-5604-46cd-8046-d6164a0e5d68 HTTP/1.1" 200 697 0.013432
2016-03-15 17:38:14.958 6978 INFO neutron.wsgi [req-2c76c429-572d-4506-b19e-37d35a3c9c4f ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.023494
2016-03-15 17:38:18.978 6978 INFO neutron.wsgi [req-11f9c726-4e78-4c1b-8d09-45efe0c3125c ] 10.0.0.11 - - [15/Mar/2016 17:38:18] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.437396
2016-03-15 17:38:19.004 6978 INFO neutron.wsgi [req-a3a13b70-52ff-495a-a840-37ae3e38326b ] 10.0.0.11 - - [15/Mar/2016 17:38:19] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.021858
2016-03-15 17:38:43.875 6978 INFO neutron.wsgi [req-4f146c47-1045-4c33-8edd-e6d4a947edea ] 10.0.0.11 - - [15/Mar/2016 17:38:43] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.050611
2016-03-15 17:38:43.901 6978 INFO neutron.wsgi [req-f5371608-453d-4035-99d9-baef06d35082 ] 10.0.0.11 - - [15/Mar/2016 17:38:43] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.022270
2016-03-15 17:39:13.556 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45680)
2016-03-15 17:39:13.749 6978 INFO neutron.wsgi [req-51c95582-652d-44a2-a446-c3bb008d5280 ] 10.0.0.11 - - [15/Mar/2016 17:39:13] "GET /v2.0/lb/pools.json HTTP/1.1" 200 226 0.190535
2016-03-15 17:39:14.560 6978 INFO neutron.wsgi [req-13918408-7c49-40df-bc76-ccc4835645f6 ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.106333
2016-03-15 17:39:14.593 6978 INFO neutron.wsgi [req-85c69b3b-9ab8-41d6-b1a3-e988c967af38 ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.030136
2016-03-15 17:39:14.725 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45690)
2016-03-15 17:39:14.790 6978 INFO neutron.wsgi [req-0f50722d-ac2d-4f04-b506-1dee4c8eff6b ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/vpn/ipsec-site-connections.json HTTP/1.1" 404 266 0.064093
2016-03-15 17:39:15.036 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45694)
2016-03-15 17:39:15.112 6978 INFO neutron.wsgi [req-8595b0f9-0025-41be-96c9-d0633f7c3e73 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/fw/firewall_policies.json HTTP/1.1" 404 266 0.075177
2016-03-15 17:39:15.125 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45696)
2016-03-15 17:39:15.388 6978 INFO neutron.wsgi [req-20c251ed-8744-4721-8bf2-db89c4609546 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/lb/vips.json HTTP/1.1" 200 225 0.261523
2016-03-15 17:39:15.581 6978 INFO neutron.wsgi [req-21eb307d-b1cb-45c2-9e3e-769ef42e8236 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/floatingips.json?tenant_id=9a04c57f89cc43cf8407ed1c06ae7a13 HTTP/1.1" 200 232 0.082304
2016-03-15 17:39:15.610 6978 INFO neutron.wsgi [req-5f6794a1-d1f1-4a34-afab-4c9d479608c1 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/networks.json?router%3Aexternal=True HTTP/1.1" 200 600 0.023160
2016-03-15 17:39:15.632 6978 INFO neutron.wsgi [req-5a2ecbf0-ef3f-4468-aa28-a33c6eb8c507 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/ports.json?tenant_id=9a04c57f89cc43cf8407ed1c06ae7a13 HTTP/1.1" 200 226 0.018892
2016-03-15 17:39:15.642 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45700)
2016-03-15 17:39:15.767 6978 INFO neutron.wsgi [req-5b006011-795d-4f72-99f2-af4cc1521242 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/lb/members.json HTTP/1.1" 200 228 0.124564
2016-03-15 17:39:15.773 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45702)
2016-03-15 17:39:15.872 6978 INFO neutron.wsgi [req-87aef2ee-260e-40c4-8624-256f2d2706cd ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/vpn/vpnservices.json HTTP/1.1" 404 266 0.098173
2016-03-15 17:39:15.936 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45704)
2016-03-15 17:39:16.011 6978 INFO neutron.wsgi [req-b8c25656-f596-44ab-84a1-c5d66350f020 ] 10.0.0.11 - - [15/Mar/2016 17:39:16] "GET /v2.0/lb/health_monitors.json HTTP/1.1" 200 493 0.073878
2016-03-15 17:39:16.084 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45706)
2016-03-15 17:39:16.151 6978 INFO neutron.wsgi [req-95283ca0-78ff-477f-bc84-68e73f98f159 ] 10.0.0.11 - - [15/Mar/2016 17:39:16] "GET /v2.0/fw/firewalls.json HTTP/1.1" 404 266 0.065183
2016-03-15 17:39:16.600 6978 INFO neutron.wsgi [req-d94c320a-5593-4143-b5cb-57f52c9a5a77 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b HTTP/1.1" 200 926 0.023071
2016-03-15 17:39:16.627 6978 INFO neutron.wsgi [req-45177b11-905c-41ac-bf8a-e3dfe16aca50 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/networks.json?id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8 HTTP/1.1" 200 597 0.024754
2016-03-15 17:39:16.639 6978 INFO neutron.wsgi [req-9363698a-59d9-4f55-a921-2579a1bfcf03 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/floatingips.json?fixed_ip_address=192.168.100.30&port_id=aa823dd0-3cb6-42da-a553-592dd52d6caf HTTP/1.1" 200 594 0.007075
2016-03-15 17:39:16.656 6978 INFO neutron.wsgi [req-ed6d5fe1-04d4-4670-9bf6-fd7a86e2f509 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/subnets.json?id=b672fe74-5604-46cd-8046-d6164a0e5d68 HTTP/1.1" 200 697 0.013659
2016-03-15 17:39:16.683 6978 INFO neutron.wsgi [req-0d025da7-d379-464a-81b1-fbf1e61301c0 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.021846

Following /etc/neutron/plugins/ml2/ml2_conf.ini

[ml2]
type_drivers = flat,vxlan
tenant_network_types = vxlan
mechanism_drivers = onos_ml2
[ml2_type_flat]
[ml2_type_vlan]
[ml2_type_gre]
tunnel_id_ranges = 1:1000
[ml2_type_vxlan]
vni_ranges = 1001:2000
[securitygroup]
enable_security_group = True
enable_ipset = True
firewall_driver = neutron.agent.linux.iptables_firewall.OVSHybridIptablesFirewallDriver
[onos]
url_path = http://10.0.0.13:8181/onos/vtn
username = karaf
password = karaf

Regards,

Igor Meneguitte Ávila.

** Affects: neutron
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Yahoo!
Engineering Team, which is subscribed to neutron.
https://bugs.launchpad.net/bugs/1557748

Title:
  ERROR nova.compute.manager Instance failed to spawn

Status in neutron:
  New

Bug description:
  Hi,

  I installed the version of the OpenStack Kilo, following this guide
  (http://docs.openstack.org/kilo/install-
  guide/install/apt/content/index.html)

  I joined the OpenStack to the controller SDN Onos version 1.3.

  I installed the plugin ml2 (networking-onos) for the integration ONOS.

  The ext-net works with flat, while the demo-net with vxlan.

  I shared the demo-net network with the admin user and network ext-net
  with the user demo.

  When I instantiate a VM with the demo-net for any user, the creation
  of the VM is performed normally and it works perfectly.

  When I instantiate a VM with network ext-net for any user, it appears
  an error message.

  I did a test assigning Floating IP for a VM with demo-net and it
  received external IP and managed to communicate with hosts on the
  Internet.

  I am pasting below the neutron-server.log and
  /etc/neutron/plugins/ml2/ml2_conf.ini.

  neutron net-list
  +--------------------------------------+----------+-------------------------------------------------------+
  | id                                   | name     | subnets                                               |
  +--------------------------------------+----------+-------------------------------------------------------+
  | 040f1e75-afa2-4d35-9a80-5e2b57d2a731 | ext-net  | 87ee275c-fd8a-45a4-897c-3215f3e44671 200.XXX.XXX.0/24  |
  | 8dc2922f-c233-4061-bcd2-f2ac5e962bf8 | demo-net | b672fe74-5604-46cd-8046-d6164a0e5d68 192.168.100.0/24 |
  +--------------------------------------+----------+-------------------------------------------------------+

  The command to create the VM with admin user on OpenStack:

  nova boot --flavor m1.tiny --image cirros-0.3.4-x86_64 --nic net-
  id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 --security-group default
  admin-instance1

  Following nova-compute.log's message:

  2016-03-15 17:37:39.262 8901 INFO nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Starting instance...
  2016-03-15 17:37:40.427 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Attempting claim: memory 512 MB, disk 1 GB
  2016-03-15 17:37:40.429 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Total memory: 7980 MB, used: 1536.00 MB
  2016-03-15 17:37:40.430 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] memory limit: 11970.00 MB, free: 10434.00 MB
  2016-03-15 17:37:40.431 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Total disk: 450 GB, used: 20.00 GB
  2016-03-15 17:37:40.432 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] disk limit not specified, defaulting to unlimited
  2016-03-15 17:37:40.630 8901 INFO nova.compute.claims [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Claim successful
  2016-03-15 17:37:42.365 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
  2016-03-15 17:37:56.739 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
  2016-03-15 17:38:05.972 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Creating image
  2016-03-15 17:38:06.530 8901 INFO nova.virt.disk.vfs.api [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Unable to import guestfs, falling back to VFSLocalFS
  2016-03-15 17:38:10.721 8901 ERROR nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Instance failed to spawn
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Traceback (most recent call last):
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2473, in _build_resources
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     yield resources
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/compute/manager.py", line 2345, in _build_and_run_instance
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     block_device_info=block_device_info)
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 2388, in spawn
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     write_to_disk=True)
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4189, in _get_guest_xml
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     context)
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/driver.py", line 4060, in _get_guest_config
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     flavor, virt_type)
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]   File "/usr/lib/python2.7/dist-packages/nova/virt/libvirt/vif.py", line 374, in get_config
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77]     _("Unexpected vif_type=%s") % vif_type)
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] NovaException: Unexpected vif_type=binding_failed
  2016-03-15 17:38:10.721 8901 TRACE nova.compute.manager [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] 
  2016-03-15 17:38:10.725 8901 INFO nova.compute.manager [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Terminating instance
  2016-03-15 17:38:10.737 8901 INFO nova.virt.libvirt.driver [-] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] During wait destroy, instance disappeared.
  2016-03-15 17:38:10.776 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Deleting instance files /var/lib/nova/instances/5a5e7552-82bc-47f8-b6dc-060d9c94dc77_del
  2016-03-15 17:38:10.778 8901 INFO nova.virt.libvirt.driver [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] [instance: 5a5e7552-82bc-47f8-b6dc-060d9c94dc77] Deletion of /var/lib/nova/instances/5a5e7552-82bc-47f8-b6dc-060d9c94dc77_del complete
  2016-03-15 17:38:12.363 8901 INFO nova.scheduler.client.report [req-211105cf-aa28-4c8b-a0bc-3c98e6d9f336 09a8fda4caf741df831ab3b6b8164590 5a0fda70f39e4214813b10d38d527208 - - -] Compute_service record updated for ('compute1', 'compute1')
  2016-03-15 17:38:21.984 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Auditing locally available compute resources for node compute1
  2016-03-15 17:38:22.776 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Total usable vcpus: 4, total allocated vcpus: 2
  2016-03-15 17:38:22.777 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Final resource view: name=compute1 phys_ram=7980MB used_ram=2048MB phys_disk=450GB used_disk=21GB total_vcpus=4 used_vcpus=2 pci_stats=<nova.pci.stats.PciDeviceStats object at 0x7f61385b81d0>
  2016-03-15 17:38:22.818 8901 INFO nova.scheduler.client.report [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Compute_service record updated for ('compute1', 'compute1')
  2016-03-15 17:38:22.818 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Compute_service record updated for compute1:compute1
  2016-03-15 17:39:23.464 8901 INFO nova.compute.resource_tracker [req-1df4bb5b-34af-4ea7-bdf6-f4883b43c0c0 - - - - -] Auditing locally available compute resources for node compute1

  Following neutron-server.log's message:

  2016-03-15 17:37:14.352 6978 INFO neutron.wsgi [req-d2ada08f-e886-420c-a1e5-764df8707503 ] 10.0.0.31 - - [15/Mar/2016 17:37:14] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.034140
  2016-03-15 17:37:20.209 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45632)
  2016-03-15 17:37:20.615 6978 INFO neutron.wsgi [req-ed1c0203-d78f-456b-8a1c-4429afc072fc ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/networks.json?id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 HTTP/1.1" 200 600 0.112256
  2016-03-15 17:37:20.763 6978 INFO neutron.wsgi [req-51cf845a-a372-4c23-8b90-24a30cbc3cca ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208 HTTP/1.1" 200 1628 0.025041
  2016-03-15 17:37:20.802 6978 INFO neutron.wsgi [req-e7188982-ae94-48bd-8e61-00f0ca58ddbe ] 10.0.0.11 - - [15/Mar/2016 17:37:20] "GET /v2.0/quotas/5a0fda70f39e4214813b10d38d527208.json HTTP/1.1" 200 407 0.035778
  2016-03-15 17:37:33.776 6978 INFO neutron.wsgi [req-5715ae4c-38a8-43a8-a7f5-cbe44448b338 ] 10.0.0.11 - - [15/Mar/2016 17:37:33] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77 HTTP/1.1" 200 226 0.020209
  2016-03-15 17:37:56.746 6978 INFO neutron.wsgi [req-3be95805-ba32-4ac9-bc71-8815426ab586 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/extensions.json HTTP/1.1" 200 5572 0.004779
  2016-03-15 17:37:56.773 6978 INFO neutron.wsgi [req-1aafd4f0-49e9-4c3f-be46-b19bf6620d81 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/networks.json?id=040f1e75-afa2-4d35-9a80-5e2b57d2a731 HTTP/1.1" 200 600 0.022951
  2016-03-15 17:37:56.802 6978 INFO neutron.wsgi [req-134f3f3e-8bbf-45e1-8e03-d8880e7ee2d7 ] 10.0.0.31 - - [15/Mar/2016 17:37:56] "GET /v2.0/security-groups.json?tenant_id=5a0fda70f39e4214813b10d38d527208 HTTP/1.1" 200 2413 0.023847
  2016-03-15 17:37:58.935 6978 INFO neutron.callbacks.manager [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Notify callbacks for port, after_create
  2016-03-15 17:37:58.936 6978 INFO neutron.callbacks.manager [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Calling callback neutron.db.l3_dvrscheduler_db._notify_l3_agent_new_port
  2016-03-15 17:37:58.983 6978 ERROR neutron.plugins.ml2.managers [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Failed to bind port e2cb725b-903d-4876-9b9c-06efb847bcfd on host compute1
  2016-03-15 17:37:58.984 6978 ERROR neutron.plugins.ml2.managers [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] Failed to bind port e2cb725b-903d-4876-9b9c-06efb847bcfd on host compute1
  2016-03-15 17:38:00.068 6978 INFO neutron.wsgi [req-33ff2d5c-6970-48b0-b488-dadbfa868b89 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "POST /v2.0/ports.json HTTP/1.1" 201 918 3.260751
  2016-03-15 17:38:00.104 6978 INFO neutron.wsgi [req-98d22f40-da0f-4812-bd6a-eb17b6a6cc80 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77 HTTP/1.1" 200 916 0.029720
  2016-03-15 17:38:00.116 6978 INFO neutron.wsgi [req-92c5180e-f41e-43cd-a373-9f7f5d22338d ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/floatingips.json?fixed_ip_address=200.131.22.107&port_id=e2cb725b-903d-4876-9b9c-06efb847bcfd HTTP/1.1" 200 232 0.007215
  2016-03-15 17:38:00.132 6978 INFO neutron.wsgi [req-a127cf8d-4815-46a8-992d-66296b2ec6dd ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/subnets.json?id=87ee275c-fd8a-45a4-897c-3215f3e44671 HTTP/1.1" 200 686 0.014205
  2016-03-15 17:38:00.154 6978 INFO neutron.wsgi [req-66c359cc-5198-4198-9472-ea5966fee596 ] 10.0.0.31 - - [15/Mar/2016 17:38:00] "GET /v2.0/ports.json?network_id=040f1e75-afa2-4d35-9a80-5e2b57d2a731&device_owner=network%3Adhcp HTTP/1.1" 200 226 0.019185
  2016-03-15 17:38:14.536 6978 INFO neutron.wsgi [req-773954d2-1be4-42da-9d1a-f4732f71f41f ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 926 0.026399
  2016-03-15 17:38:14.905 6978 INFO neutron.wsgi [req-2899a255-53f7-4cc6-a362-152aee92c3d4 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/networks.json?id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8 HTTP/1.1" 200 597 0.023312
  2016-03-15 17:38:14.915 6978 INFO neutron.wsgi [req-73ca90dc-4ecd-4c6d-8a8e-b3ca232f0970 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/floatingips.json?fixed_ip_address=192.168.100.31&port_id=10fb1dbb-9fb0-4a25-82f9-3bb621a72f6a HTTP/1.1" 200 594 0.007326
  2016-03-15 17:38:14.930 6978 INFO neutron.wsgi [req-452fac18-7144-46f2-a13b-edf4dbf7e4c3 ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/subnets.json?id=b672fe74-5604-46cd-8046-d6164a0e5d68 HTTP/1.1" 200 697 0.013432
  2016-03-15 17:38:14.958 6978 INFO neutron.wsgi [req-2c76c429-572d-4506-b19e-37d35a3c9c4f ] 10.0.0.31 - - [15/Mar/2016 17:38:14] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.023494
  2016-03-15 17:38:18.978 6978 INFO neutron.wsgi [req-11f9c726-4e78-4c1b-8d09-45efe0c3125c ] 10.0.0.11 - - [15/Mar/2016 17:38:18] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.437396
  2016-03-15 17:38:19.004 6978 INFO neutron.wsgi [req-a3a13b70-52ff-495a-a840-37ae3e38326b ] 10.0.0.11 - - [15/Mar/2016 17:38:19] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.021858
  2016-03-15 17:38:43.875 6978 INFO neutron.wsgi [req-4f146c47-1045-4c33-8edd-e6d4a947edea ] 10.0.0.11 - - [15/Mar/2016 17:38:43] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.050611
  2016-03-15 17:38:43.901 6978 INFO neutron.wsgi [req-f5371608-453d-4035-99d9-baef06d35082 ] 10.0.0.11 - - [15/Mar/2016 17:38:43] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.022270
  2016-03-15 17:39:13.556 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45680)
  2016-03-15 17:39:13.749 6978 INFO neutron.wsgi [req-51c95582-652d-44a2-a446-c3bb008d5280 ] 10.0.0.11 - - [15/Mar/2016 17:39:13] "GET /v2.0/lb/pools.json HTTP/1.1" 200 226 0.190535
  2016-03-15 17:39:14.560 6978 INFO neutron.wsgi [req-13918408-7c49-40df-bc76-ccc4835645f6 ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/ports.json?device_id=5a5e7552-82bc-47f8-b6dc-060d9c94dc77&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b&device_id=85501d7b-e16e-4004-aedb-307cb25c4716 HTTP/1.1" 200 2319 0.106333
  2016-03-15 17:39:14.593 6978 INFO neutron.wsgi [req-85c69b3b-9ab8-41d6-b1a3-e988c967af38 ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/security-groups.json?id=b1607050-1703-4586-9f57-0e5545cfc728 HTTP/1.1" 200 2413 0.030136
  2016-03-15 17:39:14.725 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45690)
  2016-03-15 17:39:14.790 6978 INFO neutron.wsgi [req-0f50722d-ac2d-4f04-b506-1dee4c8eff6b ] 10.0.0.11 - - [15/Mar/2016 17:39:14] "GET /v2.0/vpn/ipsec-site-connections.json HTTP/1.1" 404 266 0.064093
  2016-03-15 17:39:15.036 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45694)
  2016-03-15 17:39:15.112 6978 INFO neutron.wsgi [req-8595b0f9-0025-41be-96c9-d0633f7c3e73 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/fw/firewall_policies.json HTTP/1.1" 404 266 0.075177
  2016-03-15 17:39:15.125 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45696)
  2016-03-15 17:39:15.388 6978 INFO neutron.wsgi [req-20c251ed-8744-4721-8bf2-db89c4609546 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/lb/vips.json HTTP/1.1" 200 225 0.261523
  2016-03-15 17:39:15.581 6978 INFO neutron.wsgi [req-21eb307d-b1cb-45c2-9e3e-769ef42e8236 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/floatingips.json?tenant_id=9a04c57f89cc43cf8407ed1c06ae7a13 HTTP/1.1" 200 232 0.082304
  2016-03-15 17:39:15.610 6978 INFO neutron.wsgi [req-5f6794a1-d1f1-4a34-afab-4c9d479608c1 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/networks.json?router%3Aexternal=True HTTP/1.1" 200 600 0.023160
  2016-03-15 17:39:15.632 6978 INFO neutron.wsgi [req-5a2ecbf0-ef3f-4468-aa28-a33c6eb8c507 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/ports.json?tenant_id=9a04c57f89cc43cf8407ed1c06ae7a13 HTTP/1.1" 200 226 0.018892
  2016-03-15 17:39:15.642 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45700)
  2016-03-15 17:39:15.767 6978 INFO neutron.wsgi [req-5b006011-795d-4f72-99f2-af4cc1521242 ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/lb/members.json HTTP/1.1" 200 228 0.124564
  2016-03-15 17:39:15.773 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45702)
  2016-03-15 17:39:15.872 6978 INFO neutron.wsgi [req-87aef2ee-260e-40c4-8624-256f2d2706cd ] 10.0.0.11 - - [15/Mar/2016 17:39:15] "GET /v2.0/vpn/vpnservices.json HTTP/1.1" 404 266 0.098173
  2016-03-15 17:39:15.936 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45704)
  2016-03-15 17:39:16.011 6978 INFO neutron.wsgi [req-b8c25656-f596-44ab-84a1-c5d66350f020 ] 10.0.0.11 - - [15/Mar/2016 17:39:16] "GET /v2.0/lb/health_monitors.json HTTP/1.1" 200 493 0.073878
  2016-03-15 17:39:16.084 6978 INFO neutron.wsgi [-] (6978) accepted ('10.0.0.11', 45706)
  2016-03-15 17:39:16.151 6978 INFO neutron.wsgi [req-95283ca0-78ff-477f-bc84-68e73f98f159 ] 10.0.0.11 - - [15/Mar/2016 17:39:16] "GET /v2.0/fw/firewalls.json HTTP/1.1" 404 266 0.065183
  2016-03-15 17:39:16.600 6978 INFO neutron.wsgi [req-d94c320a-5593-4143-b5cb-57f52c9a5a77 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/ports.json?tenant_id=5a0fda70f39e4214813b10d38d527208&device_id=c3e3f94b-1664-4862-ade7-1d440943f01b HTTP/1.1" 200 926 0.023071
  2016-03-15 17:39:16.627 6978 INFO neutron.wsgi [req-45177b11-905c-41ac-bf8a-e3dfe16aca50 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/networks.json?id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8 HTTP/1.1" 200 597 0.024754
  2016-03-15 17:39:16.639 6978 INFO neutron.wsgi [req-9363698a-59d9-4f55-a921-2579a1bfcf03 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/floatingips.json?fixed_ip_address=192.168.100.30&port_id=aa823dd0-3cb6-42da-a553-592dd52d6caf HTTP/1.1" 200 594 0.007075
  2016-03-15 17:39:16.656 6978 INFO neutron.wsgi [req-ed6d5fe1-04d4-4670-9bf6-fd7a86e2f509 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/subnets.json?id=b672fe74-5604-46cd-8046-d6164a0e5d68 HTTP/1.1" 200 697 0.013659
  2016-03-15 17:39:16.683 6978 INFO neutron.wsgi [req-0d025da7-d379-464a-81b1-fbf1e61301c0 ] 10.0.0.31 - - [15/Mar/2016 17:39:16] "GET /v2.0/ports.json?network_id=8dc2922f-c233-4061-bcd2-f2ac5e962bf8&device_owner=network%3Adhcp HTTP/1.1" 200 927 0.021846

  Following /etc/neutron/plugins/ml2/ml2_conf.ini

  [ml2]
  type_drivers = flat,vxlan
  tenant_network_types = vxlan
  mechanism_drivers = onos_ml2
  [ml2_type_flat]
  [ml2_type_vlan]
  [ml2_type_gre]
  tunnel_id_ranges = 1:1000
  [ml2_type_vxlan]
  vni_ranges = 1001:2000
  [securitygroup]
  enable_security_group = True
  enable_ipset = True
  firewall_driver = neutron.agent.linux.iptables_firewall.OVSHybridIptablesFirewallDriver
  [onos]
  url_path = http://10.0.0.13:8181/onos/vtn
  username = karaf
  password = karaf

  Regards,

  Igor Meneguitte Ávila.

To manage notifications about this bug go to:
https://bugs.launchpad.net/neutron/+bug/1557748/+subscriptions


Follow ups