EAP Auth failure

400 views
Skip to first unread message

Mohd Jahangir

unread,
Oct 30, 2017, 1:47:38 PM10/30/17
to VOLTHA Discuss, David Bainbridge
Hi Experts

I am facing EAPOL Auth failure while using ONOS as SDN controller with Voltha. I have started freeradius server as mentioned in following link


So, my ONOS, Voltha, Ponsim devices and free radius are up and talking. But, AAA auth is failing. See below logs:

root@voltha:/# /sbin/wpa_supplicant -Dwired -ipon1_128 -c /etc/wpa_supplicant/wpa_supplicant.conf
Successfully initialized wpa_supplicant
pon1_128: Associated with 01:80:c2:00:00:03
WMM AC: Missing IEs
pon1_128: CTRL-EVENT-EAP-FAILURE EAP authentication failed
^Cpon1_128: CTRL-EVENT-DISCONNECTED bssid=01:80:c2:00:00:03 reason=3 locally_generated=1
pon1_128: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="" auth_failures=1 duration=10 reason=AUTH_FAILED
pon1_128: CTRL-EVENT-TERMINATING 
root@voltha:/# 

Here are the ONOS logs using filter log:tail | grep -i aaa

2017-10-30 17:14:10,678 | INFO  | -message-handler | ApplicationManager               | 127 - org.onosproject.onos-core-net - 1.10.7 | Application org.opencord.aaa has been activated
2017-10-30 17:15:17,858 | INFO  | -message-handler | FeaturesServiceImpl              | 20 - org.apache.karaf.features.core - 3.0.8 | Uninstalling feature aaa 1.4.0-SNAPSHOT
2017-10-30 17:15:17,859 | INFO  | -message-handler | BlueprintExtender                | 15 - org.apache.aries.blueprint.core - 1.6.1 | Destroying BlueprintContainer for bundle org.opencord.aaa/1.4.0.SNAPSHOT
2017-10-30 17:15:17,872 | WARN  | tive-installer-3 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to withdraw packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:eapol]}, priority=40000, appId=DefaultApplicationId{id=64, name=org.opencord.aaa}, nodeId=172.25.0.200, applies to=all} from of:0001aabbccddeeff: BADPARAMS
2017-10-30 17:15:17,872 | INFO  | -message-handler | AaaManager                       | 193 - org.opencord.aaa - 1.4.0.SNAPSHOT | Stopped
2017-10-30 17:15:17,872 | INFO  | AAA-radius-0     | SocketBasedRadiusCommunicator    | 193 - org.opencord.aaa - 1.4.0.SNAPSHOT | Socket was closed, exiting listener thread
2017-10-30 17:15:17,883 | INFO  | -message-handler | ApplicationManager               | 127 - org.onosproject.onos-core-net - 1.10.7 | Application org.opencord.aaa has been deactivated
2017-10-30 17:15:21,440 | INFO  | -message-handler | FeaturesServiceImpl              | 20 - org.apache.karaf.features.core - 3.0.8 | Installing feature aaa 1.4.0-SNAPSHOT

As can be seen, the highlight text says that there are some bad params in my EAPOL messages. I have verified all config param of ONOS and freeradius but they are in sync

Please guide me what am I doing wrong. Also, I couldn't find freeradius logs, please point me in that direction as well.

-Thanks
Jahangir

Shad Ansari

unread,
Oct 30, 2017, 2:15:59 PM10/30/17
to Mohd Jahangir, VOLTHA Discuss, David Bainbridge
Hi Jahangir,

Did you try deactivating/activating AAA app. This is a known issue probably related to what you are observing. See the following email thread:


Regards,
-shad


--
You received this message because you are subscribed to the Google Groups "VOLTHA Discuss" group.
To unsubscribe from this group and stop receiving emails from it, send an email to voltha-discuss+unsubscribe@opencord.org.
To post to this group, send email to voltha-...@opencord.org.
Visit this group at https://groups.google.com/a/opencord.org/group/voltha-discuss/.
To view this discussion on the web visit https://groups.google.com/a/opencord.org/d/msgid/voltha-discuss/5a75ca23-2318-4463-9206-cc9840092550%40opencord.org.
For more options, visit https://groups.google.com/a/opencord.org/d/optout.

mohd jahangir

unread,
Oct 30, 2017, 2:50:25 PM10/30/17
to Shad Ansari, VOLTHA Discuss, David Bainbridge
Hi Shad

Yeah, I did that multiple time before posting this query but still no luck. One difference is that I havent seen any type 2 error messages in onos, which indicates that AAA app is stuck in invalid state. 

That's why I wanted to check freeradius logs but couldn't find. 

Thanks
Jahangir

Amit Ghosh

unread,
Oct 31, 2017, 12:05:35 AM10/31/17
to VOLTHA Discuss, dbainbr...@gmail.com
Hi Jahangir,

From the logs

2017-10-30 17:14:10,678 | INFO  | -message-handler | ApplicationManager               | 127 - org.onosproject.onos-core-net - 1.10.7 | Application org.opencord.aaa has been activated
2017-10-30 17:15:17,858 | INFO  | -message-handler | FeaturesServiceImpl              | 20 - org.apache.karaf.features.core - 3.0.8 | Uninstalling feature aaa 1.4.0-SNAPSHOT
2017-10-30 17:15:17,859 | INFO  | -message-handler | BlueprintExtender                | 15 - org.apache.aries.blueprint.core - 1.6.1 | Destroying BlueprintContainer for bundle org.opencord.aaa/1.4.0.SNAPSHOT
2017-10-30 17:15:17,872 | WARN  | tive-installer-3 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to withdraw packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:eapol]}, priority=40000, appId=DefaultApplicationId{id=64, name=org.opencord.aaa}, nodeId=172.25.0.200, applies to=all} from of:0001aabbccddeeff: BADPARAMS

Between the activation at 2017-10-30 17:14:10,678 and the de-activation at 2017-10-30 17:15:17,858, I don't see any logs from the aaa app. I don't think the issue is with the BADPARAMS. Can you please paste complete logs/more context from the logs (don't grep on aaa)? might help to understand what is happening.

Regards,
Amit Ghosh

mohd jahangir

unread,
Nov 1, 2017, 12:11:06 PM11/1/17
to Amit Ghosh, VOLTHA Discuss, David Bainbridge
+ Voltha group

On Wed, Nov 1, 2017 at 9:38 PM, mohd jahangir <mohd.jah...@gmail.com> wrote:
Hi Amit

Thanks for the help. Here is the more detailed information. There are two files, one is having ponsim logs, voltha output etc. Another file is of ONOS logs.

Am I hitting this case? or Am i missing something in flows?

Please have a look and help me out.

-Thanks
Jahangir

--
You received this message because you are subscribed to the Google Groups "VOLTHA Discuss" group.
To unsubscribe from this group and stop receiving emails from it, send an email to voltha-discuss+unsubscribe@opencord.org.
To post to this group, send email to voltha-...@opencord.org.
Visit this group at https://groups.google.com/a/opencord.org/group/voltha-discuss/.



--
Regards
Mohd Jahangir



--
Regards
Mohd Jahangir

mohd jahangir

unread,
Nov 1, 2017, 12:12:12 PM11/1/17
to VOLTHA Discuss, David Bainbridge, Amit Ghosh
missed the attachment.

---------- Forwarded message ----------
From: mohd jahangir <mohd.jah...@gmail.com>
Date: Wed, Nov 1, 2017 at 9:38 PM
Subject: Re: [voltha-discuss] Re: EAP Auth failure
To: Amit Ghosh <ghos...@gmail.com>


Hi Amit

Thanks for the help. Here is the more detailed information. There are two files, one is having ponsim logs, voltha output etc. Another file is of ONOS logs.

Am I hitting this case? or Am i missing something in flows?

Please have a look and help me out.

-Thanks
Jahangir
On Tue, Oct 31, 2017 at 9:35 AM, Amit Ghosh <ghos...@gmail.com> wrote:

--
You received this message because you are subscribed to the Google Groups "VOLTHA Discuss" group.
To unsubscribe from this group and stop receiving emails from it, send an email to voltha-discuss+unsubscribe@opencord.org.
To post to this group, send email to voltha-...@opencord.org.
Visit this group at https://groups.google.com/a/opencord.org/group/voltha-discuss/.
To view this discussion on the web visit https://groups.google.com/a/opencord.org/d/msgid/voltha-discuss/792f2415-a86a-4cb5-be61-ce8c51cbb08d%40opencord.org.



--
Regards
Mohd Jahangir



--
Regards
Mohd Jahangir
device_snapshots.txt
ONOS_logs.txt

Amit Ghosh

unread,
Nov 2, 2017, 2:12:10 PM11/2/17
to VOLTHA Discuss, dbainbr...@gmail.com, ghos...@gmail.com
Hi Jahangir,

Somehow the installation of the packet request from the aaa app is failing...

2017-11-01 15:45:34,983 | WARN  | tive-installer-1 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:eapol]}, priority=40000, appId=DefaultApplicationId{id=64, name=org.opencord.aaa}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS

Can you also check/send the output of the following onos commands when this problem happens to see if everything looks fine?

onos> netcfg
onos> devices
onos> ports
onos> flows -s

Regards,
Amit Ghosh

mohd jahangir

unread,
Nov 3, 2017, 7:04:21 AM11/3/17
to Amit Ghosh, VOLTHA Discuss, David Bainbridge
Hi Amit,

I again tried to deactivate and activate the aaa app but, still the same issue. Here is the requested info.

onos> app activate org.opencord.aaa 
Activated org.opencord.aaa
onos> 
onos> 
onos> flows -s
deviceId=of:0001aabbccddeeff, flowRuleCount=3
    ADDED, bytes=0, packets=0, table=0, priority=10000, selector=[IN_PORT:128, ETH_TYPE:ipv4, IP_PROTO:2], treatment=[immediate=[OUTPUT:CONTROLLER]]
    ADDED, bytes=0, packets=0, table=0, priority=10000, selector=[IN_PORT:0, ETH_TYPE:ipv4, IP_PROTO:2], treatment=[immediate=[OUTPUT:CONTROLLER]]
    ADDED, bytes=0, packets=0, table=0, priority=1000, selector=[IN_PORT:128, ETH_TYPE:eapol], treatment=[immediate=[OUTPUT:CONTROLLER]]
onos> 
onos> 
onos> 
onos> netcfg
netcfg             netcfg-registry    
onos> netcfg
{
  "apps" : {
    "org.opencord.aaa" : {
      "AAA" : {
        "radiusIp" : "172.25.0.100",
        "nasIp" : "5.6.7.8",
        "radiusServerPort" : "1812",
        "radiusSecret" : "SECRET",
        "bindings" : [ {
          "mac" : "de:ad:be:ef:ba:11",
          "s-tag" : 2,
          "c-tag" : 2,
          "nas_port_id" : "location"
        }, {
          "mac" : "de:ad:be:ef:ca:fe",
          "s-tag" : 2,
          "c-tag" : 122,
          "nas_port_id" : "location"
        } ]
      }
    }
  },
  "devices" : {
    "of:0001000000000005" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "1004"
      }
    },
    "of:0001000000000006" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "1005"
      }
    },
    "of:0001000000000003" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "1002"
      }
    },
    "of:0001000000000004" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "1003"
      }
    },
    "of:0001aabbccddeeff" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "2"
      }
    },
    "of:0001000000000002" : {
      "basic" : {
        "driver" : "pmc-olt"
      },
      "accessDevice" : {
        "uplink" : "0",
        "vlan" : "1001"
      }
    }
  },
  "ports" : {
    "of:00000cafecafecafe/3" : {
      "interfaces" : [ {
        "name" : "yoyoma",
        "ips" : [ "10.0.2.2/24" ],
        "mac" : "00:00:00:01:02:00"
      } ]
    },
    "of:00000cafecafecafe/2" : {
      "interfaces" : [ {
        "name" : "yoyoma2",
        "ips" : [ "10.0.2.3/24" ],
        "mac" : "00:00:00:01:03:00"
      } ]
    },
    "of:0000000000000001/2" : {
      "interfaces" : [ {
        "name" : "yoyoma1",
        "ips" : [ "10.0.2.1/24" ],
        "mac" : "00:00:00:01:01:00"
      } ]
    },
    "of:00000cafecafecafe/4" : {
      "interfaces" : [ {
        "name" : "yoyoma4",
        "ips" : [ "10.0.2.4/24" ],
        "mac" : "00:00:00:01:04:00"
      } ]
    }
  },
  "layouts" : { },
  "hosts" : { },
  "regions" : { },
  "links" : { }
}
onos> 
onos> 
onos> 
onos> 
onos> 
onos> 
onos> ports
id=of:0001aabbccddeeff, available=true, local-status=connected 1h23m ago, role=MASTER, type=SWITCH, mfr=cord porject, hw=simulated pon, sw=simulated pon, serial=990406b6c7ad43298b81d27631cca979, driver=pmc-olt, channelId=172.25.0.1:48952, locType=geo, managementAddress=172.25.0.1, name=of:0001aabbccddeeff, protocol=OF_13
  port=0, state=enabled, type=fiber, speed=0 , portName=nni, portMac=00:00:00:00:00:00
  port=128, state=enabled, type=fiber, speed=0 , portName=uni-128, portMac=00:00:00:00:00:80
onos> 
onos> 
onos> devices
id=of:0001aabbccddeeff, available=true, local-status=connected 1h23m ago, role=MASTER, type=SWITCH, mfr=cord porject, hw=simulated pon, sw=simulated pon, serial=990406b6c7ad43298b81d27631cca979, driver=pmc-olt, channelId=172.25.0.1:48952, locType=geo, managementAddress=172.25.0.1, name=of:0001aabbccddeeff, protocol=OF_13
onos> 
-------------------------------------

ONOS Logs:

2017-11-03 10:58:05,104 | INFO  | -message-handler | AaaManager                       | 184 - org.opencord.aaa - 1.4.0.SNAPSHOT | Created default PacketCustomizer
2017-11-03 10:58:05,109 | INFO  | AAA-radius-0     | SocketBasedRadiusCommunicator    | 184 - org.opencord.aaa - 1.4.0.SNAPSHOT | UDP listener thread starting up
2017-11-03 10:58:05,123 | WARN  | tive-installer-2 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:eapol]}, priority=40000, appId=DefaultApplicationId{id=64, name=org.opencord.aaa}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-03 10:58:05,126 | INFO  | AAA-radius-0     | SocketBasedRadiusCommunicator    | 184 - org.opencord.aaa - 1.4.0.SNAPSHOT | UDP listener thread starting up
2017-11-03 10:58:05,130 | INFO  | -message-handler | AaaManager                       | 184 - org.opencord.aaa - 1.4.0.SNAPSHOT | Started
2017-11-03 10:58:05,133 | INFO  | -message-handler | ApplicationManager               | 127 - org.onosproject.onos-core-net - 1.10.7 | Application org.opencord.aaa has been activated

-Thanks
Jahangir


--
You received this message because you are subscribed to the Google Groups "VOLTHA Discuss" group.
To unsubscribe from this group and stop receiving emails from it, send an email to voltha-discuss+unsubscribe@opencord.org.
To post to this group, send email to voltha-...@opencord.org.
Visit this group at https://groups.google.com/a/opencord.org/group/voltha-discuss/.
To view this discussion on the web visit https://groups.google.com/a/opencord.org/d/msgid/voltha-discuss/176b41f7-8c28-4907-9ef1-706ada31e708%40opencord.org.



--
Regards
Mohd Jahangir

Amit Ghosh

unread,
Nov 3, 2017, 11:56:27 AM11/3/17
to VOLTHA Discuss, ghos...@gmail.com, dbainbr...@gmail.com
Hi Jahangir,

Not able to see anything in the configuration which could cause the problem. One thing is that we have tried this with ONOS 1.10.6.

You seem to be using ONOS 1.10.7, can you try with 1.10.6 and see what is the behaviour?

Also when you run the tests again do send the output of the following commands (along with the commands mentioned earlier and the complete ONOS logs)

onos> flows
onos> apps -a

Regards,
Amit Ghosh

Mohd Jahangir

unread,
Nov 8, 2017, 8:48:16 AM11/8/17
to VOLTHA Discuss, ghos...@gmail.com, dbainbr...@gmail.com
Hi Amit

Here are some logs which I took after deactivating and activating Ponsim device from VOLTHA cli. As can be seen, some new messages have started now. Also, there is a DHCP fail messages. It that the cause of AAA and DHCP not working. I have verified the network-cfg file, it seems to be fine. See the highlighted text of below logs.

Please have a look and suggest what to do to make AAA and DHCP work.

2017-11-08 13:39:36,525 | INFO  | ew I/O worker #1 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Switch disconnected callback for sw:org.onosproject.driver.handshaker.DefaultSwitchHandshaker [/172.25.0.1:34672 DPID[00:01:aa:bb:cc:dd:ee:ff]]. Cleaning up ...
2017-11-08 13:39:36,526 | INFO  | ew I/O worker #1 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | org.onosproject.driver.handshaker.DefaultSwitchHandshaker [/172.25.0.1:34672 DPID[00:01:aa:bb:cc:dd:ee:ff]]:removal called
2017-11-08 13:39:36,527 | INFO  | ew I/O worker #1 | MeterStatsCollector              | 167 - org.onosproject.onos-providers-openflow-meter - 1.10.7 | Stopping Meter Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:39:36,537 | INFO  | ew I/O worker #1 | GroupStatsCollector              | 166 - org.onosproject.onos-providers-openflow-group - 1.10.7 | Stopping Group Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:39:36,544 | INFO  | ew I/O worker #1 | PortStatsCollector               | 163 - org.onosproject.onos-providers-openflow-device - 1.10.7 | Stopping Port Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:39:36,546 | INFO  | ew I/O worker #1 | DeviceManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Device of:0001aabbccddeeff disconnected from this node
2017-11-08 13:39:36,555 | INFO  | spatch-topology0 | DhcpL2Relay                      | 178 - org.opencord.dhcpl2relay - 1.2.0.SNAPSHOT | DHCP Server connectPoint is null
2017-11-08 13:39:36,555 | ERROR | spatch-topology0 | DhcpL2Relay                      | 178 - org.opencord.dhcpl2relay - 1.2.0.SNAPSHOT | Master of none, can't relay DHCP Message to server
2017-11-08 13:39:36,575 | INFO  | nos-topo-build-2 | TopologyManager                  | 127 - org.onosproject.onos-core-net - 1.10.7 | Topology DefaultTopology{time=626613205424564, creationTime=1510148376566, computeCost=304675, clusters=0, devices=0, links=0} changed
2017-11-08 13:39:39,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=778, flags=[], portNo=any)]
2017-11-08 13:39:42,543 | WARN  | d wheel timer #2 | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFMeterStatsRequestVer13(xid=0, flags=[], meterId=-1)]
2017-11-08 13:39:42,544 | WARN  | d wheel timer #2 | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFGroupStatsRequestVer13(xid=771, flags=[], group=all)]
2017-11-08 13:39:42,546 | WARN  | d wheel timer #2 | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFGroupDescStatsRequestVer13(xid=772, flags=[])]
2017-11-08 13:39:44,197 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=779, flags=[], portNo=any)]
2017-11-08 13:39:47,531 | INFO  | nager-background | DeviceManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Marking unreachable device of:0001aabbccddeeff offline
2017-11-08 13:39:49,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=780, flags=[], portNo=any)]
2017-11-08 13:39:54,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=781, flags=[], portNo=any)]
2017-11-08 13:39:59,193 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=782, flags=[], portNo=any)]
2017-11-08 13:40:01,647 | INFO  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | New switch connection from /172.25.0.1:40770
2017-11-08 13:40:01,707 | INFO  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Sending OF_13 Hello to /172.25.0.1:40770
2017-11-08 13:40:01,734 | INFO  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Received port desc reply for switch at [/172.25.0.1:40770 DPID[00:01:aa:bb:cc:dd:ee:ff]]
2017-11-08 13:40:01,752 | WARN  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Received OFError BAD_REQUEST. It seems of:0001aabbccddeeff does not support Meter.
2017-11-08 13:40:01,765 | INFO  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Received switch description reply OFDescStatsReplyVer13(xid=4294967288, flags=[], mfrDesc=cord porject, hwDesc=simulated pon, swDesc=simulated pon, serialNum=7d9d52ecfdc54f0ead72f23ca5f0b188, dpDesc=n/a) from switch at /172.25.0.1:40770
2017-11-08 13:40:01,766 | INFO  | ew I/O worker #2 | Controller                       | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Driver pmc-olt assigned to device 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,767 | INFO  | ew I/O worker #2 | Controller                       | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | OpenFlow handshaker found for device 469198549413631: org.onosproject.driver.handshaker.DefaultSwitchHandshaker [? DPID[00:01:aa:bb:cc:dd:ee:ff]]
2017-11-08 13:40:01,767 | INFO  | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Added switch 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,768 | INFO  | ew I/O worker #2 | MeterStatsCollector              | 167 - org.onosproject.onos-providers-openflow-meter - 1.10.7 | Starting Meter Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,774 | INFO  | ew I/O worker #2 | GroupStatsCollector              | 166 - org.onosproject.onos-providers-openflow-group - 1.10.7 | Starting Group Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,778 | INFO  | ew I/O worker #2 | DeviceManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Local role is MASTER for of:0001aabbccddeeff
2017-11-08 13:40:01,788 | INFO  | ew I/O worker #2 | DeviceManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Device of:0001aabbccddeeff connected
2017-11-08 13:40:01,789 | INFO  | ew I/O worker #2 | PortStatsCollector               | 163 - org.onosproject.onos-providers-openflow-device - 1.10.7 | Starting Port Stats collection thread for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,789 | INFO  | spatch-topology0 | DhcpL2Relay                      | 178 - org.opencord.dhcpl2relay - 1.2.0.SNAPSHOT | DHCP Server connectPoint is null
2017-11-08 13:40:01,790 | ERROR | spatch-topology0 | DhcpL2Relay                      | 178 - org.opencord.dhcpl2relay - 1.2.0.SNAPSHOT | Master of none, can't relay DHCP Message to server
2017-11-08 13:40:01,790 | INFO  | ew I/O worker #2 | OFChannelHandler                 | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Processing 0 pending port status messages for 00:01:aa:bb:cc:dd:ee:ff
2017-11-08 13:40:01,791 | INFO  | spatch-topology0 | IgmpManager                      | 177 - org.opencord.onos-app-igmpproxy - 1.2.0.SNAPSHOT | connectPoint not configured for device of:0001aabbccddeeff
2017-11-08 13:40:01,798 | WARN  | tive-installer-1 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:ipv4, IP_PROTO:17, UDP_SRC:67]}, priority=40000, appId=DefaultApplicationId{id=109, name=org.opencord.dhcpl2relay}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,798 | WARN  | tive-installer-2 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:eapol]}, priority=40000, appId=DefaultApplicationId{id=64, name=org.opencord.aaa}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,798 | WARN  | tive-installer-3 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:ipv4, IP_PROTO:17, UDP_SRC:68]}, priority=40000, appId=DefaultApplicationId{id=109, name=org.opencord.dhcpl2relay}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,798 | WARN  | tive-installer-1 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:ipv4, IP_PROTO:17, UDP_SRC:68, UDP_DST:67]}, priority=40000, appId=DefaultApplicationId{id=60, name=org.onosproject.dhcp}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,799 | WARN  | tive-installer-1 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:arp]}, priority=40000, appId=DefaultApplicationId{id=119, name=org.onosproject.provider.host}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,799 | WARN  | tive-installer-1 | PacketManager                    | 127 - org.onosproject.onos-core-net - 1.10.7 | Failed to install packet request DefaultPacketRequest{selector=DefaultTrafficSelector{criteria=[ETH_TYPE:arp]}, priority=40000, appId=DefaultApplicationId{id=60, name=org.onosproject.dhcp}, nodeId=172.25.0.200, applies to=all} to of:0001aabbccddeeff: BADPARAMS
2017-11-08 13:40:01,803 | INFO  | ew I/O worker #2 | ntrollerImpl$OpenFlowSwitchAgent | 162 - org.onosproject.onos-protocols-openflow-ctl - 1.10.7 | Transitioned switch 00:01:aa:bb:cc:dd:ee:ff to MASTER
2017-11-08 13:40:01,804 | INFO  | nos-topo-build-3 | TopologyManager                  | 127 - org.onosproject.onos-core-net - 1.10.7 | Topology DefaultTopology{time=626638433128159, creationTime=1510148401793, computeCost=136829, clusters=1, devices=1, links=0} changed
2017-11-08 13:40:01,810 | INFO  | spatch-topology0 | IgmpManager                      | 177 - org.opencord.onos-app-igmpproxy - 1.2.0.SNAPSHOT | connectPoint not configured for device of:0001aabbccddeeff
2017-11-08 13:40:01,856 | INFO  | ce-operations-12 | IgmpManager                      | 177 - org.opencord.onos-app-igmpproxy - 1.2.0.SNAPSHOT | IgmpProxy filter for of:0001aabbccddeeff on 128 installed.
2017-11-08 13:40:01,857 | INFO  | ce-operations-11 | IgmpManager                      | 177 - org.opencord.onos-app-igmpproxy - 1.2.0.SNAPSHOT | IgmpProxy filter for of:0001aabbccddeeff on 0 installed.
2017-11-08 13:40:01,887 | INFO  | ce-operations-13 | Olt                              | 183 - org.opencord.olt-app - 1.4.0.SNAPSHOT | Eapol filter for of:0001aabbccddeeff on 128 installed.
2017-11-08 13:40:02,871 | INFO  | of-event-stats-8 | DistributedGroupStore            | 129 - org.onosproject.onos-core-dist - 1.10.7 | Group AUDIT: Setting device of:0001aabbccddeeff initial AUDIT completed
2017-11-08 13:40:04,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=783, flags=[], portNo=any)]
2017-11-08 13:40:09,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=784, flags=[], portNo=any)]
2017-11-08 13:40:14,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=785, flags=[], portNo=any)]
2017-11-08 13:40:19,195 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=786, flags=[], portNo=any)]
2017-11-08 13:40:24,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=787, flags=[], portNo=any)]
2017-11-08 13:40:29,197 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=788, flags=[], portNo=any)]
2017-11-08 13:40:34,193 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=789, flags=[], portNo=any)]
2017-11-08 13:40:39,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=790, flags=[], portNo=any)]
2017-11-08 13:40:44,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=791, flags=[], portNo=any)]
2017-11-08 13:40:49,197 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=792, flags=[], portNo=any)]
2017-11-08 13:40:54,198 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=793, flags=[], portNo=any)]
2017-11-08 13:40:59,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=794, flags=[], portNo=any)]
2017-11-08 13:41:04,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=795, flags=[], portNo=any)]
2017-11-08 13:41:09,193 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=796, flags=[], portNo=any)]
2017-11-08 13:41:14,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=797, flags=[], portNo=any)]
2017-11-08 13:41:19,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=798, flags=[], portNo=any)]
2017-11-08 13:41:24,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=799, flags=[], portNo=any)]
2017-11-08 13:41:29,193 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=800, flags=[], portNo=any)]
2017-11-08 13:41:34,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=801, flags=[], portNo=any)]
2017-11-08 13:41:39,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=802, flags=[], portNo=any)]
2017-11-08 13:41:44,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=803, flags=[], portNo=any)]
2017-11-08 13:41:49,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=804, flags=[], portNo=any)]
2017-11-08 13:41:54,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=805, flags=[], portNo=any)]
2017-11-08 13:41:59,194 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=806, flags=[], portNo=any)]
2017-11-08 13:42:04,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=807, flags=[], portNo=any)]
2017-11-08 13:42:09,196 | WARN  | tstats-collector | DefaultSwitchHandshaker          | 161 - org.onosproject.onos-protocols-openflow-api - 1.10.7 | Dropping messages for switch 00:01:aa:bb:cc:dd:ee:ff because channel is not connected: [OFPortStatsRequestVer13(xid=808, flags=[], portNo=any)]


onos> apps -a -s
*   4 org.onosproject.optical-model        1.10.7   Optical information model
*   5 org.onosproject.openflow-base        1.10.7   OpenFlow Provider
*   6 org.onosproject.drivers              1.10.7   Default device drivers
*   7 org.onosproject.hostprovider         1.10.7   Host Location Provider
*  24 org.opencord.config                  1.4.0.SNAPSHOT CORD configuration meta application
*  60 org.onosproject.dhcp                 1.10.7   DHCP Server App
*  62 org.onosproject.olt                  1.4.0.SNAPSHOT Optical Line Terminal App
*  63 org.opencord.sadis                   1.2.0.SNAPSHOT Subscriber And Device Information App
*  64 org.opencord.aaa                     1.4.0.SNAPSHOT AAA App
*  81 org.opencord.mcast                   1.4.0.SNAPSHOT CORD Multicast application
* 109 org.opencord.dhcpl2relay             1.2.0.SNAPSHOT DHCP Relay Agent App


-Thanks
Jahangir

David Bainbridge

unread,
Nov 16, 2017, 10:20:32 AM11/16/17
to Mohd Jahangir, VOLTHA Discuss, ghos...@gmail.com
I think DHCP is giving the error because you don't have any DHCP L2 relay configuration in you netcfg. Also I see you are running org.onosproject.dhcp, this shouldn't be needed to voltha. can you post a `devices` and `ports` response as well from onos?

mohd jahangir

unread,
Nov 16, 2017, 11:51:09 AM11/16/17
to VOLTHA Discuss, David Bainbridge, Amit Ghosh
Adding group as well.

---------- Forwarded message ----------
From: mohd jahangir <mohd.jah...@gmail.com>
Date: Thu, Nov 16, 2017 at 10:12 PM
Subject: Re: [voltha-discuss] Re: EAP Auth failure
To: David Bainbridge <dbainbr...@gmail.com>


Hi David

The scenario is bit different now. After struggling with VOLTHA+ONOS+Radius authentication I decided to perform the same steps of authentication using Mininet emulated network (in place of VOLTHA + OLT + ONU) along with ONOS and free radius. Now, I am having two OpenFlow switches, each having 2 hosts, pingall is successful. Also, switches and hosts are discovered by ONOS. This all is running in same VM having IP 192.168.56.101. Moreover, I have build ONOS for code and running with debug options. ONOS is having all the apps like aaa, sadis etc active, Check file onos_output.txt for additional details. Also, check word doc topology.jpg. I am building this setup from grounds-up to have a better understanding of each config parameter for AAA and SADIS.

There is this second VM which is just having freeradius installed and having IP 192.168.56.102. I followed these two links as my base along with additional material on the internet.


Now the problem: I am able to send EAPOL messages from one of my mininet host using wpa_supplicant. These messages are reaching ONOS AAA and subsequently to radius server. However, 
radius server is not responding correctly. This seems to be SSL error even after I cleaned all certificate and generated again. If you have any insight into this, help appreciated.

Second question: After this is successful, I will replace mininet network with VOLTHA + Tibit OLT and ONU. I assume things will work as ONOS treat VOLTHA as OpenFlow switch like it does to mininet OF switch.
Is my understanding correct? Or do I need to setup it differently? Also, If possible can you share some SADIS config doc?

Attachment: freeradius.logs, wpa_supplicant.jpeg

-Thanks
Jahangir
--
Regards
Mohd Jahangir



--
Regards
Mohd Jahangir
onos_output.txt
topology.JPG
wpa_supplicant_failed.JPG
freeradius.logs
Reply all
Reply to author
Forward
0 new messages