OFPErrorMsg received in local controller

[Li Ma]

2016-06-07 09:56:33.222 ERROR dragonflow.controller.ryu_base_app [-] OFPErrorMsg received: type=0x04 code=0x08 message=0x04 0x0e 0x00 0x60 0x34 0x58 0x9b 0xbb 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x06 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0x00 0x00 0x00 0x00 0x00 0x01 0x00 0x18 0x00 0x01 0xd3 0x08 0x00 0x00 0x00 0x21 0x00 0x00 0x00 0x37
2016-06-07 09:56:33.223 DEBUG RyuDFAdapter [-] EventOFPErrorMsg received.
version=0x4, msg_type=0x1, msg_len=0x4c, xid=0x34589bbb
 `-- msg_type: OFPT_ERROR(1)
OFPErrorMsg(type=0x4, code=0x8, data=b'\x04\x0e\x00\x60\x34\x58\x9b\xbb\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x06\x00\x00\x00\x00\x00\x00\x03\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x00\x01\x00\x18\x00\x01\xd3\x08\x00\x00\x00\x21\x00\x00\x00\x37')
 |-- type: OFPET_BAD_MATCH(4)
 |-- code: OFPBMC_BAD_MASK(8) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:282
2016-06-07 09:56:33.223 DEBUG RyuDFAdapter [-] `-- data: version=0x4, msg_type=0xe, msg_len=0x60, xid=0x34589bbb
     `-- msg_type: OFPT_FLOW_MOD(14) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:289
2016-06-07 09:56:33.224 ERROR dragonflow.controller.ryu_base_app [-] OFPErrorMsg received: type=0x02 code=0x00 message=0x04 0x0e 0x00 0x68 0x34 0x58 0x9b 0xbc 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x48 0x00 0x00 0x00 0x00 0x00 0x00 0x64 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0x00 0x00 0x00 0x00 0x00 0x01 0x00 0x12 0x00 0x01 0x0e 0x04 0x00 0x00 0x00 0x02 0x80 0x00 0x0a 0x02
2016-06-07 09:56:33.225 DEBUG RyuDFAdapter [-] EventOFPErrorMsg received.
version=0x4, msg_type=0x1, msg_len=0x4c, xid=0x34589bbc
 `-- msg_type: OFPT_ERROR(1)
OFPErrorMsg(type=0x2, code=0x0, data=b'\x04\x0e\x00\x68\x34\x58\x9b\xbc\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x48\x00\x00\x00\x00\x00\x00\x64\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x00\x01\x00\x12\x00\x01\x0e\x04\x00\x00\x00\x02\x80\x00\x0a\x02')
 |-- type: OFPET_BAD_ACTION(2)
 |-- code: OFPBAC_BAD_TYPE(0) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:282
2016-06-07 09:56:33.225 DEBUG RyuDFAdapter [-] `-- data: version=0x4, msg_type=0xe, msg_len=0x68, xid=0x34589bbc
     `-- msg_type: OFPT_FLOW_MOD(14) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:289
2016-06-07 09:56:33.229 DEBUG dragonflow.db.api_nb [-] Event update: Action:set, Table:ovsinterface, Key:22adb216-2f79-4eff-9550-f1a2a4ae16ae Value:uuid:22adb216-2f79-4eff-9550-f1a2a4ae16ae, ofport:3, name:tapa5bdd6e6-17, admin_state:up, type:vm, iface_id:a5bdd6e6-1793-4f83-afe3-58d467986f71, peer:, attached_mac:fa:16:3e:8b:06:10, remote_ip:, tunnel_type: Topic:None from (pid=22696) _read_db_changes_from_queue /opt/stack/dragonflow/dragonflow/db/api_nb.py:165
2016-06-07 09:56:33.229 INFO dragonflow.controller.topology [-] Ovs port updated: uuid:22adb216-2f79-4eff-9550-f1a2a4ae16ae, ofport:3, name:tapa5bdd6e6-17, admin_state:up, type:vm, iface_id:a5bdd6e6-1793-4f83-afe3-58d467986f71, peer:, attached_mac:fa:16:3e:8b:06:10, remote_ip:, tunnel_type:
2016-06-07 09:56:33.230 INFO dragonflow.controller.topology [-] A local logical port({u'parent_name': None, u'macs': [u'fa:16:3e:8b:06:10'], u'name': u'', u'allowed_address_pairs': [], u'lswitch': u'b34490b8-ca2e-47ce-acfd-440e375a01a7', u'enabled': True, u'topic': u'2c9c20e5e8b847ea82fb0bc3b1f28b1d', u'ips': [u'192.168.33.3'], u'device_owner': u'compute:nova', u'chassis': u'nick-fedora', u'version': 0, u'tunnel_key': 2, u'port_security_enabled': True, u'tag': None, u'id': u'a5bdd6e6-1793-4f83-afe3-58d467986f71', u'security_groups': [u'bd8df767-153f-4a9b-88c0-f9f3e146b459']}{'is_local': True, 'ofport': 3, 'local_network_id': 1}) is online
2016-06-07 09:56:33.231 INFO dragonflow.controller.df_local_controller [-] Updating local logical port = {u'parent_name': None, u'macs': [u'fa:16:3e:8b:06:10'], u'name': u'', u'allowed_address_pairs': [], u'lswitch': u'b34490b8-ca2e-47ce-acfd-440e375a01a7', u'enabled': True, u'topic': u'2c9c20e5e8b847ea82fb0bc3b1f28b1d', u'ips': [u'192.168.33.3'], u'device_owner': u'compute:nova', u'chassis': u'nick-fedora', u'version': 0, u'tunnel_key': 2, u'port_security_enabled': True, u'tag': None, u'id': u'a5bdd6e6-1793-4f83-afe3-58d467986f71', u'security_groups': [u'bd8df767-153f-4a9b-88c0-f9f3e146b459']}{'is_local': True, 'ofport': 3, 'local_network_id': 1}, original port = {u'parent_name': None, u'macs': [u'fa:16:3e:8b:06:10'], u'name': u'', u'allowed_address_pairs': [], u'lswitch': u'b34490b8-ca2e-47ce-acfd-440e375a01a7', u'enabled': True, u'topic': u'2c9c20e5e8b847ea82fb0bc3b1f28b1d', u'ips': [u'192.168.33.3'], u'device_owner': u'compute:nova', u'chassis': u'nick-fedora', u'version': 0, u'tunnel_key': 2, u'port_security_enabled': True, u'tag': None, u'id': u'a5bdd6e6-1793-4f83-afe3-58d467986f71', u'security_groups': [u'bd8df767-153f-4a9b-88c0-f9f3e146b459']}{'is_local': True, 'ofport': 3, 'local_network_id': 1}
2016-06-07 09:56:33.234 INFO dragonflow.controller.dhcp_app [-] Regiter VM as DHCP client::port <a5bdd6e6-1793-4f83-afe3-58d467986f71>
2016-06-07 09:56:33.240 ERROR dragonflow.controller.ryu_base_app [-] OFPErrorMsg received: type=0x02 code=0x00 message=0x04 0x0e 0x00 0x68 0x34 0x58 0x9b 0xbd 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x00 0x03 0x00 0x00 0x00 0x00 0x00 0x00 0x64 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0xff 0x00 0x00 0x00 0x00 0x00 0x01 0x00 0x12 0x80 0x00 0x00 0x04 0x00 0x00 0x00 0x03 0x80 0x00 0x0a 0x02
2016-06-07 09:56:33.241 DEBUG RyuDFAdapter [-] EventOFPErrorMsg received.
version=0x4, msg_type=0x1, msg_len=0x4c, xid=0x34589bbd
 `-- msg_type: OFPT_ERROR(1)
OFPErrorMsg(type=0x2, code=0x0, data=b'\x04\x0e\x00\x68\x34\x58\x9b\xbd\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x03\x00\x00\x00\x00\x00\x00\x64\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\xff\x00\x00\x00\x00\x00\x01\x00\x12\x80\x00\x00\x04\x00\x00\x00\x03\x80\x00\x0a\x02')
 |-- type: OFPET_BAD_ACTION(2)
 |-- code: OFPBAC_BAD_TYPE(0) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:282
2016-06-07 09:56:33.242 DEBUG RyuDFAdapter [-] `-- data: version=0x4, msg_type=0xe, msg_len=0x68, xid=0x34589bbd
     `-- msg_type: OFPT_FLOW_MOD(14) from (pid=22696) error_msg_handler /usr/lib/python2.7/site-packages/ryu/controller/ofp_handler.py:289
2016-06-07 09:56:45.550 INFO dragonflow.controller.dhcp_app [-] sending DHCP offer for port IP 192.168.33.3 port id a5bdd6e6-1793-4f83-afe3-58d467986f71
2016-06-07 09:56:45.558 INFO dragonflow.controller.dhcp_app [-] sending DHCP ACK for port IP 192.168.33.3 port id a5bdd6e6-1793-4f83-afe3-58d467986f71

[Li Ma (nick-ma-z)]

I found it in my new dev machine. Fedora Server 22, the latest devstack and dragonflow.
When I boot a new VM, the error messages appear.

[Omer Anson (omer-anson)]

In my experience, this usually happens when using an old version of OVS which doesn't support connection tracking (pre- 2.5). This may happen on Fedora 23 if the kernel wasn't upgraded, but on Fedora 22 OVS should be installed from git.

@Li Ma -
Could you please upload the local.conf you used?
Do you also have the stack.sh log?

Thanks,

[Li Ma (nick-ma-z)]

I use the fedora 22 repository to install OVS, which is definitely 2.5.0. I checked the version, so I set the OVS_FROM_GIT is False. If I set to True, the OVS is installed from git. I guess it is the problem of the OVS package from repo.

[Li Ma (nick-ma-z)]

After I upgrade the fedora 22 kernel, it is working. ;-) Thanks for advice.

[yaowei (yaowei-deactivatedaccount)]

Hi, what's your current kervel version of fedora 22? I meet the same problem

[Li Ma (nick-ma-z)]

4.4.14-200.fc22.x86_64