-
Type: Bug
-
Status: In Progress (View Workflow)
-
Priority: Low
-
Resolution: Unresolved
-
Affects Version/s: VOLTHA v2.5
-
Fix Version/s: VOLTHA v2.12
-
Component/s: bbsim
-
Labels:None
-
Story Points:3
-
Epic Link:
When we receive a flow removal we should clear the DHCP state machine so that we can restart it when a new flow is received:
default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:09Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=55 ClassifierOPbits=5 ClassifierOVid=555 Cookie=3659178765203948 DstPort=0 FlowId=1905 FlowType=downstream GemportId=2194 InnerVlan=55 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=555 PortNo=37120 SrcPort=0 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:19Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=1203 ClassifierOPbits=255 ClassifierOVid=900 Cookie=3659176391591833 DstPort=0 FlowId=2265 FlowType=downstream GemportId=2405 InnerVlan=1203 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=900 PortNo=37120 SrcPort=0 UniID=0 module=ONU // First DHCP Flow default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=800 ClassifierIVid=0 ClassifierOPbits=7 ClassifierOVid=444 Cookie=3659177266491809 DstPort=67 FlowId=2650 FlowType=upstream GemportId=2491 InnerVlan=0 IntfId=9 IpProto=17 OnuId=16 OnuSn=BBSM00000910 OuterVlan=444 PortNo=37120 SrcPort=68 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=debug msg="Changing Service.DHCPState InternalState from created to dhcp_started" IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=debug msg=HandleDHCPStart GemPortId=2491 IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=info msg="DHCPDiscovery Sent" IntfId=9 OnuId=16 OnuSn=BBSM00000910 ServiceName=voip module=DHCP default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_started to dhcp_discovery_sent" IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:34Z" level=debug msg="ONU receives FlowRemove" FlowId=2650 FlowType=upstream IntfId=9 OnuId=16 SerialNumber=BBSM00000910 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:40Z" level=info msg="DHCPRequest Sent" IntfId=9 OfferedIp=192.168.243.77 OnuId=16 OnuSn=BBSM00000910 ServiceName=voip module=DHCP default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:40Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_discovery_sent to dhcp_request_sent" IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:45Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=0 ClassifierOPbits=255 ClassifierOVid=1203 Cookie=3659177637759457 DstPort=0 FlowId=2265 FlowType=upstream GemportId=2405 InnerVlan=0 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=1203 PortNo=37120 SrcPort=0 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:48Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=0 ClassifierOPbits=7 ClassifierOVid=444 Cookie=3659175025581738 DstPort=0 FlowId=2924 FlowType=upstream GemportId=2547 InnerVlan=0 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=444 PortNo=37120 SrcPort=0 UniID=0 module=ONU // Second DHCP flow (note that flow was removed before being pushed again) default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:51Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=800 ClassifierIVid=0 ClassifierOPbits=7 ClassifierOVid=444 Cookie=3659177266491809 DstPort=67 FlowId=2996 FlowType=upstream GemportId=2547 InnerVlan=0 IntfId=9 IpProto=17 OnuId=16 OnuSn=BBSM00000910 OuterVlan=444 PortNo=37120 SrcPort=68 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:51Z" level=error msg="Can't start DHCP for this Service" IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 err="event start_dhcp inappropriate in current state dhcp_request_sent" module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:52Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=800 ClassifierIVid=0 ClassifierOPbits=5 ClassifierOVid=0 Cookie=3659175935558580 DstPort=0 FlowId=3027 FlowType=upstream GemportId=2194 InnerVlan=0 IntfId=9 IpProto=2 OnuId=16 OnuSn=BBSM00000910 OuterVlan=0 PortNo=37120 SrcPort=0 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:53Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=800 ClassifierIVid=0 ClassifierOPbits=5 ClassifierOVid=55 Cookie=3659176090299810 DstPort=67 FlowId=3053 FlowType=upstream GemportId=2194 InnerVlan=0 IntfId=9 IpProto=17 OnuId=16 OnuSn=BBSM00000910 OuterVlan=55 PortNo=37120 SrcPort=68 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:53Z" level=debug msg="Changing Service.DHCPState InternalState from created to dhcp_started" IntfId=9 Name=vod OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:53Z" level=debug msg=HandleDHCPStart GemPortId=2194 IntfId=9 Name=vod OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:53Z" level=info msg="DHCPDiscovery Sent" IntfId=9 OnuId=16 OnuSn=BBSM00000910 ServiceName=vod module=DHCP default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:53Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_started to dhcp_discovery_sent" IntfId=9 Name=vod OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:54Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=444 ClassifierOPbits=7 ClassifierOVid=333 Cookie=3659175111432702 DstPort=0 FlowId=2924 FlowType=downstream GemportId=2547 InnerVlan=444 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=333 PortNo=37120 SrcPort=0 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:28:54Z" level=debug msg="OLT receives FlowAdd for ONU" ClassifierEthType=0 ClassifierIVid=0 ClassifierOPbits=5 ClassifierOVid=55 Cookie=3659178315208206 DstPort=0 FlowId=1905 FlowType=upstream GemportId=2194 InnerVlan=0 IntfId=9 IpProto=0 OnuId=16 OnuSn=BBSM00000910 OuterVlan=55 PortNo=37120 SrcPort=0 UniID=0 module=ONU default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:06Z" level=info msg="DHCPRequest Sent" IntfId=9 OfferedIp=192.168.244.72 OnuId=16 OnuSn=BBSM00000910 ServiceName=vod module=DHCP default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:06Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_discovery_sent to dhcp_request_sent" IntfId=9 Name=vod OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:12Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_request_sent to dhcp_ack_received" IntfId=9 Name=vod OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:12Z" level=info msg="DHCP State machine completed" IntfId=9 OnuId=16 OnuSn=BBSM00000910 ServiceName=vod module=DHCP default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:34Z" level=warning msg="DHCP failed, resetting DHCP State" DHCPState=dhcp_request_sent IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE default/bbsim0-7856df4cd6-stmb5[bbsim]: time="2020-09-28T22:29:34Z" level=debug msg="Changing Service.DHCPState InternalState from dhcp_request_sent to dhcp_failed" IntfId=9 Name=voip OnuId=16 OnuSn=BBSM00000910 module=SERVICE