openvswitch / ovs-issues

Issue tracker repo for Open vSwitch
10 stars 3 forks source link

Flow flapping when passing data #331

Open asantos-vk opened 4 months ago

asantos-vk commented 4 months ago

I recently ran into this problem and haven't been able to move forward since. What happens is that the flows are in a state where they are created and deleted immediately afterwards, remaining in this cycle while we try to pass data.

The environment is using the ryu controller. I'd like some troubleshooting tips.

syslog at 20:51

May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00550|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:36, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=235f, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00551|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:136, type=57(ovs_vport), flags=0, seq=235f, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00552|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:36, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2360, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00553|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:136, type=57(ovs_vport), flags=0, seq=2360, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00554|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:36, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2361, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00555|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:136, type=57(ovs_vport), flags=0, seq=2361, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00556|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:40, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2362, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00557|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:140, type=57(ovs_vport), flags=0, seq=2362, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00558|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:48, type=18(family-defined), flags=1[REQUEST], seq=59c, pid=3966521664
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00559|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:1380, type=16(family-defined), flags=0, seq=59c, pid=3966521664
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00560|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:36, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2363, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00561|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:136, type=57(ovs_vport), flags=0, seq=2363, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00562|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:36, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2364, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00563|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:136, type=57(ovs_vport), flags=0, seq=2364, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00564|netlink_socket|DBG|nl_sock_transact_multiple__ (Success): nl(len:40, type=57(ovs_vport), flags=9[REQUEST][ECHO], seq=2365, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00565|netlink_socket|DBG|nl_sock_recv__ (Success): nl(len:140, type=57(ovs_vport), flags=0, seq=2365, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00566|jsonrpc|DBG|unix:/var/run/openvswitch/db.sock: send request, method="transact", params=["Open_vSwitch",{"where":[["_uuid","==",["uuid","2b6f4344-1186-4ab6-9b52-818e874912e7"]]],"row":{"statistics":["map",[["rx_bytes",57364],["rx_packets",830],["tx_bytes",1371],["tx_packets",7]]]},"op":"update","table":"Interface"},{"where":[["_uuid","==",["uuid","01b8b1cc-1a52-4527-b1d8-109a8c2a25f2"]]],"row":{"status":["map",[["last_error","Connection refused"],["sec_since_connect","2287"],["state","ACTIVE"]]]},"op":"update","table":"Controller"},{"where":[["_uuid","==",["uuid","3e9947b5-cd2a-487e-9350-17ca0d72e82f"]]],"row":{"status":["map",[["last_error","Connection refused"],["sec_since_connect","2283"],["state","ACTIVE"]]]},"op":"update","table":"Controller"},{"lock":"ovs_vswitchd","op":"assert"}], id=512
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00567|poll_loop|DBG|wakeup due to [POLLIN] on fd 16 (<->/var/run/openvswitch/db.sock) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00568|jsonrpc|DBG|unix:/var/run/openvswitch/db.sock: received notification, method="update3", params=[["monid","Open_vSwitch"],"00000000-0000-0000-0000-000000000000",{"Controller":{"01b8b1cc-1a52-4527-b1d8-109a8c2a25f2":{"modify":{"status":["map",[["sec_since_connect","2287"]]]}},"3e9947b5-cd2a-487e-9350-17ca0d72e82f":{"modify":{"status":["map",[["sec_since_connect","2283"]]]}}},"Interface":{"2b6f4344-1186-4ab6-9b52-818e874912e7":{"modify":{"statistics":["map",[["rx_bytes",57364],["rx_packets",830]]]}}}}]
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00569|jsonrpc|DBG|unix:/var/run/openvswitch/db.sock: received reply, result=[{"count":1},{"count":1},{"count":1},{}], id=512
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00320|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00570|poll_loop|DBG|wakeup due to [POLLIN] on fd 57 (127.0.0.1:47030<->127.0.0.1:6633) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00571|vconn|DBG|tcp:127.0.0.1:6633: received: OFPST_FLOW request (OF1.4) (xid=0x5ab42556): table=13
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00572|vconn|DBG|tcp:127.0.0.1:6633: sent (Success): OFPST_FLOW reply (OF1.4) (xid=0x5ab42556):
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.827s, table=13, n_packets=1, n_bytes=98, priority=10,ip,reg1=0x10,reg2=0x1,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.821s, table=13, n_packets=0, n_bytes=0, priority=10,ip,reg1=0x10,reg2=0x2,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.827s, table=13, n_packets=74, n_bytes=5041, priority=10,ip,reg1=0x1,reg2=0x1,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.821s, table=13, n_packets=0, n_bytes=0, priority=10,ip,reg1=0x1,reg2=0x2,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.817s, table=13, n_packets=0, n_bytes=0, priority=1,ip,reg1=0x10,reg2=0,reg4=0,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0x0, duration=183.817s, table=13, n_packets=0, n_bytes=0, priority=1,ip,reg1=0x1,reg2=0,reg4=0,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:09 agw-lte ovs-vswitchd:  cookie=0xfffffffffffffffe, duration=2281.484s, table=13, n_packets=426, n_bytes=29509, priority=0 actions=drop
May 24 20:51:09 agw-lte sessiond[246470]: I0524 20:51:09.734552 246470 SessionState.cpp:637] Updating used charging credit for Rule=policy100M Rating Group=0 Service Identifier=0
May 24 20:51:09 agw-lte sessiond[246470]: I0524 20:51:09.735412 246470 LocalEnforcer.cpp:433] Received stats for 1 active sessions and 0 stale sessions
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00321|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00322|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00745|poll_loop(revalidator3)|DBG|wakeup due to 500-ms timeout at ofproto/ofproto-dpif-upcall.c:982 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00746|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=2366, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00747|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=2366, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00748|dpif(revalidator3)|DBG|system@ovs-system: get_stats success
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00749|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=2367, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00750|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=2367, pid=2998801930 done(0)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00751|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00281|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00282|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00752|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00753|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00283|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00573|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:09 agw-lte sctpd[246442]: I0524 20:51:09.930503 246650 sctp_connection.cpp:208] Unhandled notification type 32777
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00046|poll_loop(handler2)|DBG|wakeup due to [POLLIN] on fd 32 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00323|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00047|netlink_socket(handler2)|DBG|nl_sock_recv__ (Success): nl(len:293, type=59(ovs_packet), flags=0, seq=0, pid=0,genl(cmd=1,version=1)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00046|poll_loop(handler1)|DBG|wakeup due to [POLLIN] on fd 31 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:09 agw-lte ovs-vswitchd: ovs|00048|dpif(handler2)|DBG|system@ovs-system: miss upcall:
May 24 20:51:09 agw-lte ovs-vswitchd: recirc_id(0),dp_hash(0),skb_priority(0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64,tp_src=2152,tp_dst=2152,flags(csum|key)),in_port(4),skb_mark(0),ct_state(0),ct_zone(0),ct_mark(0),ct_label(0),eth_type(0x0800),ipv4(src=192.168.128.12,dst=8.8.8.8,proto=17,tos=0,ttl=64,frag=no),udp(src=42786,dst=53)
May 24 20:51:09 agw-lte ovs-vswitchd: packet_type=(1,0x800),nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=42786,tp_dst=53 udp_csum:33bc
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00049|tunnel(handler2)|DBG|tunnel port port 4: g_6402000a (gtpu: ::->10.0.2.100, key=flow, legacy_l3, dp port=4, ttl=64)
May 24 20:51:10 agw-lte ovs-vswitchd:  receive from flow packet_type=(1,0x800),tun_id=0x3,tun_src=10.0.2.100,tun_dst=10.0.2.1,tun_ipv6_src=::,tun_ipv6_dst=::,tun_gbp_id=0,tun_gbp_flags=0,tun_tos=0,tun_ttl=64,tun_erspan_ver=0,gtpu_flags=0,gtpu_msgtype=0,tun_flags=csum|key,in_port=4,nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=42786,tp_dst=53
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00050|netdev_vport(handler2)|DBG|gtpu flags 0 msg_type 0 dev->tnl_cfg.dst_port 26632 == (26632, 26632) flow 10.0.2.100 cfg 10.0.2.100
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00051|netlink_socket(handler2)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=2368, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00052|netlink_socket(handler2)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=2368, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00053|dpif(handler2)|DBG|system@ovs-system: get_stats success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00054|netlink_socket(handler2)|DBG|nl_sock_transact_multiple__ (Success): nl(len:404, type=58(ovs_flow), flags=401[REQUEST][ATOMIC], seq=2369, pid=2998801930,genl(cmd=1,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00055|dpif(handler2)|DBG|system@ovs-system: put[create] ufid:e89bf279-8531-4a58-a3a0-4237b71ae346 recirc_id(0),dp_hash(0/0),skb_priority(0/0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64/0,tp_src=2152/0,tp_dst=2152/0,flags(-df+csum+key)),in_port(4),skb_mark(0/0),ct_state(0/0),ct_zone(0/0),ct_mark(0/0),ct_label(0/0),eth_type(0x0800),ipv4(src=192.168.128.12/0.0.0.0,dst=8.8.8.8/254.0.0.0,proto=17/0,tos=0/0,ttl=64/0,frag=no),udp(src=42786/0,dst=53/0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00056|poll_loop(handler2)|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif-upcall.c:756 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00324|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00574|poll_loop|DBG|wakeup due to [POLLIN] on fd 56 (127.0.0.1:57690<->127.0.0.1:6654) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00575|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,tun_id=0x3,in_port=3 out_port:0 actions=set_field:02:00:00:00:00:01->eth_src,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x605->reg9,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00576|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=LOCAL,nw_dst=192.168.128.12 out_port:0 actions=set_field:0x605->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00577|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=15577,nw_dst=192.168.128.12 out_port:0 actions=set_field:0x605->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00578|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00579|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=15577,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00580|poll_loop|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif.c:1972 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00160|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00161|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00754|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at ofproto/ofproto-dpif-upcall.c:984 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00162|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00755|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=236a, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00756|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=236a, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00757|dpif(revalidator3)|DBG|system@ovs-system: get_stats success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00758|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=236b, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00759|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:76, type=58(ovs_flow), flags=2[MULTI], seq=236b, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00284|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00760|dpif(revalidator3)|DBG|system@ovs-system: flow_dump ufid:e89bf279-8531-4a58-a3a0-4237b71ae346 <empty>, packets:1, bytes:67, used:0.132s
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00285|netlink_socket(revalidator4)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=236b, pid=2998801930 done(0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00286|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00761|tunnel(revalidator3)|DBG|tunnel port port 4: g_6402000a (gtpu: ::->10.0.2.100, key=flow, legacy_l3, dp port=4, ttl=64)
May 24 20:51:10 agw-lte ovs-vswitchd:  receive from flow packet_type=(1,0x800),tun_id=0x3,tun_src=10.0.2.100,tun_dst=10.0.2.1,tun_ipv6_src=::,tun_ipv6_dst=::,tun_gbp_id=0,tun_gbp_flags=0,tun_tos=0,tun_ttl=64,tun_erspan_ver=0,gtpu_flags=0,gtpu_msgtype=0,tun_flags=csum|key,in_port=4,nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=42786,tp_dst=53
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00762|netdev_vport(revalidator3)|DBG|gtpu flags 0 msg_type 0 dev->tnl_cfg.dst_port 26632 == (26632, 26632) flow 10.0.2.100 cfg 10.0.2.100
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00163|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00763|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:52, type=58(ovs_flow), flags=9[REQUEST][ECHO], seq=1993, pid=244407,genl(cmd=2,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00764|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:76, type=58(ovs_flow), flags=0, seq=1993, pid=244407,genl(cmd=2,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00765|dpif(revalidator3)|DBG|system@ovs-system: flow_del ufid:e89bf279-8531-4a58-a3a0-4237b71ae346 recirc_id(0),dp_hash(0),skb_priority(0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64,tp_src=2152,tp_dst=2152,flags(csum|key)),in_port(4),skb_mark(0),ct_state(0),ct_zone(0),ct_mark(0),ct_label(0),eth_type(0x0800),ipv4(src=192.168.128.12,dst=8.8.8.8,proto=17,tos=0,ttl=64,frag=no),udp(src=42786,dst=53), packets:1, bytes:67, used:0.132s
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00766|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00164|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00165|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00287|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00767|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00166|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00768|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00167|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00581|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00168|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00582|poll_loop|DBG|wakeup due to [POLLIN] on fd 56 (127.0.0.1:57690<->127.0.0.1:6654) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00583|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,tun_id=0x3,in_port=3 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00584|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=LOCAL,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00585|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=15577,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00586|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=LOCAL,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00587|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=15577,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00588|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00589|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=5,ip,nw_dst=192.168.128.12 out_port:0 actions=CONTROLLER:65535
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00590|poll_loop|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif.c:1972 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00169|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00170|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00591|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00171|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00769|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at ofproto/ofproto-dpif-upcall.c:984 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00770|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=236c, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00771|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=236c, pid=2998801930 done(0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00772|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00288|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00289|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00172|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00773|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00173|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00174|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00175|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00774|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00290|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00592|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00176|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00325|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte sctpd[246442]: I0524 20:51:10.220510 246650 sctp_connection.cpp:208] Unhandled notification type 32777
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00326|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00327|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00328|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00329|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00775|poll_loop(revalidator3)|DBG|wakeup due to 499-ms timeout at ofproto/ofproto-dpif-upcall.c:982 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00776|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=236d, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00777|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=236d, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00778|dpif(revalidator3)|DBG|system@ovs-system: get_stats success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00779|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=236e, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00780|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=236e, pid=2998801930 done(0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00781|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00291|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00292|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00782|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00783|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00293|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00593|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00330|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte sctpd[246442]: I0524 20:51:10.720433 246650 sctp_connection.cpp:208] Unhandled notification type 32777
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00057|poll_loop(handler2)|DBG|wakeup due to [POLLIN] on fd 32 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00047|poll_loop(handler1)|DBG|wakeup due to [POLLIN] on fd 31 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00331|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00058|netlink_socket(handler2)|DBG|nl_sock_recv__ (Success): nl(len:286, type=59(ovs_packet), flags=0, seq=0, pid=0,genl(cmd=1,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00059|dpif(handler2)|DBG|system@ovs-system: miss upcall:
May 24 20:51:10 agw-lte ovs-vswitchd: recirc_id(0),dp_hash(0),skb_priority(0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64,tp_src=2152,tp_dst=2152,flags(csum|key)),in_port(4),skb_mark(0),ct_state(0),ct_zone(0),ct_mark(0),ct_label(0),eth_type(0x0800),ipv4(src=192.168.128.12,dst=8.8.8.8,proto=17,tos=0,ttl=64,frag=no),udp(src=39662,dst=53)
May 24 20:51:10 agw-lte ovs-vswitchd: packet_type=(1,0x800),nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=39662,tp_dst=53 udp_csum:114c
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00060|tunnel(handler2)|DBG|tunnel port port 4: g_6402000a (gtpu: ::->10.0.2.100, key=flow, legacy_l3, dp port=4, ttl=64)
May 24 20:51:10 agw-lte ovs-vswitchd:  receive from flow packet_type=(1,0x800),tun_id=0x3,tun_src=10.0.2.100,tun_dst=10.0.2.1,tun_ipv6_src=::,tun_ipv6_dst=::,tun_gbp_id=0,tun_gbp_flags=0,tun_tos=0,tun_ttl=64,tun_erspan_ver=0,gtpu_flags=0,gtpu_msgtype=0,tun_flags=csum|key,in_port=4,nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=39662,tp_dst=53
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00061|netdev_vport(handler2)|DBG|gtpu flags 0 msg_type 0 dev->tnl_cfg.dst_port 26632 == (26632, 26632) flow 10.0.2.100 cfg 10.0.2.100
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00062|netlink_socket(handler2)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=236f, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00063|netlink_socket(handler2)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=236f, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00064|dpif(handler2)|DBG|system@ovs-system: get_stats success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00065|netlink_socket(handler2)|DBG|nl_sock_transact_multiple__ (Success): nl(len:404, type=58(ovs_flow), flags=401[REQUEST][ATOMIC], seq=2370, pid=2998801930,genl(cmd=1,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00066|dpif(handler2)|DBG|system@ovs-system: put[create] ufid:cfdf9226-d1ae-4e2d-998d-8f1dcd5cef71 recirc_id(0),dp_hash(0/0),skb_priority(0/0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64/0,tp_src=2152/0,tp_dst=2152/0,flags(-df+csum+key)),in_port(4),skb_mark(0/0),ct_state(0/0),ct_zone(0/0),ct_mark(0/0),ct_label(0/0),eth_type(0x0800),ipv4(src=192.168.128.12/0.0.0.0,dst=8.8.8.8/254.0.0.0,proto=17/0,tos=0/0,ttl=64/0,frag=no),udp(src=39662/0,dst=53/0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00067|poll_loop(handler2)|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif-upcall.c:756 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00332|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00594|poll_loop|DBG|wakeup due to [POLLIN] on fd 56 (127.0.0.1:57690<->127.0.0.1:6654) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00595|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,tun_id=0x3,in_port=3 out_port:0 actions=set_field:02:00:00:00:00:01->eth_src,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0xb05->reg9,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00596|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=LOCAL,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xb05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00597|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=15577,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xb05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00598|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00599|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=15577,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00600|poll_loop|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif.c:1972 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00177|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00178|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00179|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00784|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at ofproto/ofproto-dpif-upcall.c:984 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00785|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=2371, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00786|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=2371, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00787|dpif(revalidator3)|DBG|system@ovs-system: get_stats success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00788|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=2372, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00294|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00295|netlink_socket(revalidator4)|DBG|nl_sock_recv__ (Success): nl(len:44, type=58(ovs_flow), flags=2[MULTI], seq=2372, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00296|dpif(revalidator4)|DBG|system@ovs-system: flow_dump ufid:cfdf9226-d1ae-4e2d-998d-8f1dcd5cef71 <empty>, packets:0, bytes:0, used:never
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00297|tunnel(revalidator4)|DBG|tunnel port port 4: g_6402000a (gtpu: ::->10.0.2.100, key=flow, legacy_l3, dp port=4, ttl=64)
May 24 20:51:10 agw-lte ovs-vswitchd:  receive from flow packet_type=(1,0x800),tun_id=0x3,tun_src=10.0.2.100,tun_dst=10.0.2.1,tun_ipv6_src=::,tun_ipv6_dst=::,tun_gbp_id=0,tun_gbp_flags=0,tun_tos=0,tun_ttl=64,tun_erspan_ver=0,gtpu_flags=0,gtpu_msgtype=0,tun_flags=csum|key,in_port=4,nw_src=192.168.128.12,nw_dst=8.8.8.8,nw_proto=17,nw_tos=0,nw_ecn=0,nw_ttl=64,tp_src=39662,tp_dst=53
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00298|netdev_vport(revalidator4)|DBG|gtpu flags 0 msg_type 0 dev->tnl_cfg.dst_port 26632 == (26632, 26632) flow 10.0.2.100 cfg 10.0.2.100
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00299|netlink_socket(revalidator4)|DBG|nl_sock_transact_multiple__ (Success): nl(len:52, type=58(ovs_flow), flags=9[REQUEST][ECHO], seq=1994, pid=244407,genl(cmd=2,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00300|netlink_socket(revalidator4)|DBG|nl_sock_recv__ (Success): nl(len:44, type=58(ovs_flow), flags=0, seq=1994, pid=244407,genl(cmd=2,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00789|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=2372, pid=2998801930 done(0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00790|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00301|dpif(revalidator4)|DBG|system@ovs-system: flow_del ufid:cfdf9226-d1ae-4e2d-998d-8f1dcd5cef71 recirc_id(0),dp_hash(0),skb_priority(0),tunnel(tun_id=0x3,src=10.0.2.100,dst=10.0.2.1,ttl=64,tp_src=2152,tp_dst=2152,flags(csum|key)),in_port(4),skb_mark(0),ct_state(0),ct_zone(0),ct_mark(0),ct_label(0),eth_type(0x0800),ipv4(src=192.168.128.12,dst=8.8.8.8,proto=17,tos=0,ttl=64,frag=no),udp(src=39662,dst=53), packets:0, bytes:0, used:never
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00302|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00791|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00180|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00181|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00182|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00303|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00601|poll_loop|DBG|wakeup due to [POLLIN] on fd 56 (127.0.0.1:57690<->127.0.0.1:6654) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00792|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00602|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,tun_id=0x3,in_port=3 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00603|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=LOCAL,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00604|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=15577,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00605|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=LOCAL,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00606|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=15577,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00607|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00608|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=5,ip,nw_dst=192.168.128.12 out_port:0 actions=CONTROLLER:65535
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00609|poll_loop|DBG|wakeup due to 0-ms timeout at ofproto/ofproto-dpif.c:1972 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00183|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00184|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:238 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00185|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00186|poll_loop(urcu5)|DBG|wakeup due to [POLLIN] on fd 37 (FIFO pipe:[4997629]) at lib/ovs-rcu.c:365 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00793|poll_loop(revalidator3)|DBG|wakeup due to [POLLIN] on fd 39 (FIFO pipe:[4998303]) at ofproto/ofproto-dpif-upcall.c:984 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00794|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=2373, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00304|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00305|netlink_socket(revalidator4)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=2373, pid=2998801930 done(0)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00306|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00795|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00307|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00308|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00796|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:10 agw-lte ovs-vswitchd: ovs|00610|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:10 agw-lte sctpd[246442]: I0524 20:51:10.990355 246650 sctp_connection.cpp:208] Unhandled notification type 32777
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00333|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00334|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00335|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00336|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte mme[246580]: [INFO] Sending S6A-AUTHENTICATION_INFORMATION_REQUEST with IMSI: 724066703539145
May 24 20:51:11 agw-lte subscriberdb[244586]: WARNING:root:Subscriber not found: IMSI724066703539145
May 24 20:51:11 agw-lte mme[246580]: [DEBUG] Success logging event: attach_reject
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00337|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00797|poll_loop(revalidator3)|DBG|wakeup due to 500-ms timeout at ofproto/ofproto-dpif-upcall.c:982 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00798|netlink_socket(revalidator3)|DBG|nl_sock_transact_multiple__ (Success): nl(len:24, type=56(ovs_datapath), flags=9[REQUEST][ECHO], seq=2374, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00799|netlink_socket(revalidator3)|DBG|nl_sock_recv__ (Success): nl(len:120, type=56(ovs_datapath), flags=0, seq=2374, pid=2998801930,genl(cmd=3,version=2)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00800|dpif(revalidator3)|DBG|system@ovs-system: get_stats success
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00801|netlink_socket(revalidator3)|DBG|nl_sock_send__ (Success): nl(len:32, type=58(ovs_flow), flags=305[REQUEST][ACK][DUMP], seq=2375, pid=2998801930,genl(cmd=3,version=1)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00309|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00310|netlink_socket(revalidator4)|DBG|nl_sock_recv__ (Success): nl(len:20, type=3(done), flags=2[MULTI], seq=2375, pid=2998801930 done(0)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00311|dpif(revalidator4)|DBG|system@ovs-system: dumped all flows
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00802|dpif(revalidator3)|DBG|system@ovs-system: dumped all flows
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00312|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00313|poll_loop(revalidator4)|DBG|wakeup due to [POLLIN] on fd 41 (FIFO pipe:[4999253]) at lib/ovs-thread.c:341 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00803|dpif(revalidator3)|DBG|system@ovs-system: flow_dump_destroy success
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00611|poll_loop|DBG|wakeup due to [POLLIN] on fd 24 (FIFO pipe:[4991170]) at vswitchd/bridge.c:423 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00338|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00339|poll_loop(monitor6)|DBG|wakeup due to 100-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00340|poll_loop(monitor6)|DBG|wakeup due to 101-ms timeout at ofproto/ofproto-dpif-monitor.c:238 (0% CPU usage)
May 24 20:51:11 agw-lte sctpd[246442]: I0524 20:51:11.710448 246650 sctp_connection.cpp:208] Unhandled notification type 32777
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00612|poll_loop|DBG|wakeup due to [POLLIN] on fd 57 (127.0.0.1:47030<->127.0.0.1:6633) at lib/stream-fd.c:157 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00613|vconn|DBG|tcp:127.0.0.1:6633: received: OFPST_FLOW request (OF1.4) (xid=0x5ab42557): table=13
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00614|vconn|DBG|tcp:127.0.0.1:6633: sent (Success): OFPST_FLOW reply (OF1.4) (xid=0x5ab42557):
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.829s, table=13, n_packets=1, n_bytes=98, priority=10,ip,reg1=0x10,reg2=0x1,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.824s, table=13, n_packets=0, n_bytes=0, priority=10,ip,reg1=0x10,reg2=0x2,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.830s, table=13, n_packets=75, n_bytes=5108, priority=10,ip,reg1=0x1,reg2=0x1,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.824s, table=13, n_packets=0, n_bytes=0, priority=10,ip,reg1=0x1,reg2=0x2,reg3=0,reg4=0x1,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.820s, table=13, n_packets=0, n_bytes=0, priority=1,ip,reg1=0x10,reg2=0,reg4=0,reg11=0,metadata=0x149a5777fd4cb1,nw_dst=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0x0, duration=185.820s, table=13, n_packets=0, n_bytes=0, priority=1,ip,reg1=0x1,reg2=0,reg4=0,reg11=0,metadata=0x149a5777fd4cb1,nw_src=192.168.128.12 actions=drop
May 24 20:51:11 agw-lte ovs-vswitchd:  cookie=0xfffffffffffffffe, duration=2283.487s, table=13, n_packets=428, n_bytes=29648, priority=0 actions=drop
May 24 20:51:11 agw-lte sessiond[246470]: I0524 20:51:11.737468 246470 SessionState.cpp:637] Updating used charging credit for Rule=policy100M Rating Group=0 Service Identifier=0
May 24 20:51:11 agw-lte sessiond[246470]: I0524 20:51:11.737689 246470 LocalEnforcer.cpp:433] Received stats for 1 active sessions and 0 stale sessions
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00048|poll_loop(handler1)|DBG|wakeup due to [POLLIN] on fd 31 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00068|poll_loop(handler2)|DBG|wakeup due to [POLLIN] on fd 32 (unknown anon_inode:[eventpoll]) at lib/dpif-netlink.c:2785 (0% CPU usage)
May 24 20:51:11 agw-lte ovs-vswitchd: ovs|00049|netlink_socket(handler1)|DBG|nl_sock_recv__ (Success): nl(len:287, type=59(ovs_packet), flags=0, seq=0, pid=0,genl(cmd=1,version=1)

ovs-vswitchd logs at 20:51 (tz is 4h later)

2024-05-25T00:51:10.139Z|00578|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.139Z|00579|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=15577,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.147Z|00583|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,tun_id=0x3,in_port=3 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.147Z|00584|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=LOCAL,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.147Z|00585|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=15577,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.147Z|00586|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=LOCAL,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.148Z|00587|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=15577,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.148Z|00588|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.148Z|00589|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=5,ip,nw_dst=192.168.128.12 out_port:0 actions=CONTROLLER:65535
2024-05-25T00:51:10.925Z|00595|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,tun_id=0x3,in_port=3 out_port:0 actions=set_field:02:00:00:00:00:01->eth_src,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0xb05->reg9,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.925Z|00596|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=LOCAL,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xb05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.926Z|00597|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=15577,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xb05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.926Z|00598|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.926Z|00599|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=15577,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.931Z|00602|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,tun_id=0x3,in_port=3 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.931Z|00603|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=LOCAL,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.931Z|00604|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,ip,in_port=15577,nw_dst=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.931Z|00605|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=LOCAL,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.932Z|00606|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): DEL priority=0,arp,in_port=15577,arp_tpa=192.168.128.12 cookie:0x0/0xffffffffffffffff actions=drop
2024-05-25T00:51:10.932Z|00607|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:10.932Z|00608|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=5,ip,nw_dst=192.168.128.12 out_port:0 actions=CONTROLLER:65535
2024-05-25T00:51:11.923Z|00616|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,tun_id=0x3,in_port=3 out_port:0 actions=set_field:02:00:00:00:00:01->eth_src,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0xd05->reg9,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:11.923Z|00617|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=LOCAL,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xd05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:11.923Z|00618|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,ip,in_port=15577,nw_dst=192.168.128.12 out_port:0 actions=set_field:0xd05->tun_id,set_field:10.0.2.100->tun_dst,set_field:0x3->reg8,set_field:0x3->reg9,set_field:ff:ff:ff:ff:ff:ff->eth_dst,set_field:0x149a5777fd4cb1->metadata,goto_table:1
2024-05-25T00:51:11.923Z|00619|vconn|DBG|tcp:127.0.0.1:6654: received: OFPT_FLOW_MOD (OF1.3) (xid=0x1): ADD priority=10,arp,in_port=LOCAL,arp_tpa=192.168.128.12 out_port:0 actions=set_field:0x149a5777fd4cb1->metadata,goto_table:1