search

soulwing / tnm4j

A simplified SNMP API for Java, based on Jürgen Schönwälder's Tnm extension for Tcl.
GNU General Public License v3.0
41 stars 18 forks source link

SNMP Listener can not read trap from OIDs that are entries in a table #9

Closed 2operate closed 5 years ago

2operate commented 5 years ago

Hi

I have created an SNMP listener and it works for most of the received traps. However when the listener receives traps from a "table" something odd occurs.

The extractIndexes method in MibbleIndexExtractor.java causes an exception.

The oid String and instanceOid String are for some reason identical so it causes an out of bound exception when it tries to create the suboid and does not print out the trap.

I am listening to traps from an Ericsson object and all the other traps that are not inside a table works fine. I am using the ERICSSON-ALARM-MIB and all the required imports for that MIB. The overview of this MIB can be seen at https://mibs.observium.org/mib/ERICSSON-ALARM-MIB/

Any suggestions on how to fix this issue?

I have also asked the question on https://stackoverflow.com/questions/55240710/snmp-listener-can-not-read-trap-from-oids-that-are-entries-in-a-table

Best regards Michael

ceharris commented 5 years ago

Could you please share your listener code? You could either create a Gist that contains it and just put the link to it here, or (it it's not too big) paste it in to this thread.

2operate commented 5 years ago

Thanks for fast reply. The listener code looks like this:

listener = SnmpFactory.getInstance().newListener(listenAddress, portNumber, mib);
        listener.addHandler(new SnmpNotificationHandler() {
            @Override
            public Boolean handleNotification(SnmpNotificationEvent event) {
                if (logger.isDebugEnabled()) {
                    logger.debug("Event: " + event);
                }
                saveTrap(event);
                return true;
            }
        });

And these are the MIBs we load

        Mib mib = MibFactory.getInstance().newMib();
            mib.load("SNMPv2-SMI");
            mib.load("SNMPv2-TC");
            mib.load("SNMPv2-CONF");
            mib.load("SNMP-FRAMEWORK-MIB");
            mib.load("SNMP-TARGET-MIB");
            mib.load("SNMP-NOTIFICATION-MIB");
            mib.load("IANA-ITU-ALARM-TC-MIB");
            mib.load("ITU-ALARM-TC-MIB");
            mib.load("INET-ADDRESS-MIB");
            mib.load("RFC1155-SMI");
            mib.load("RFC1158-MIB");
            mib.load("RFC-1212");
            mib.load("RFC1213-MIB");
            mib.load("RMON-MIB");
            mib.load("RFC1271-MIB");
            mib.load("TOKEN-RING-RMON-MIB");
            mib.load("RMON2-MIB");
            mib.load("ALARM-MIB");
            mib.load("ERICSSON-TOP-MIB");
            mib.load("ERICSSON-TC-MIB");
            mib.load("ERICSSON-ALARM-TC-MIB");
            mib.load("ERICSSON-ALARM-PC-MIB");
            mib.load("ERICSSON-ALARM-MIB");
ceharris commented 5 years ago

Can you provide the output of the logger.debug call?

Is the exception is being thrown in the saveTrap method? Can you share the source of that method? Could you add the stack trace to this issue as a comment, too?

ceharris commented 5 years ago

I think the issue is that you're trying to use what is returned in the trap as though it is a table row, when it fact, it is just a reference to a table row. Need to see the saveTrap source...

2operate commented 5 years ago

Heres the log:

2019-03-20 08:24:08,972 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 MibbleIndexExtractor.java:66] - 1.3.6.1.4.1.193.183.4.1.3.5.1.5 2019-03-20 08:24:08,973 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 MibbleIndexExtractor.java:68] - 1.3.6.1.4.1.193.183.4.1.3.5.1.5 2019-03-20 08:24:08,973 WARN [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:139] - listener exception: java.lang.StringIndexOutOfBoundsException: String index out of range: -1 java.lang.StringIndexOutOfBoundsException: String index out of range: -1 at java.lang.String.substring(Unknown Source) ~[?:1.8.0_201] at org.soulwing.snmp.provider.mibble.MibbleIndexExtractor.extractIndexes(MibbleIndexExtractor.java:69) ~[2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.soulwing.snmp.provider.snmp4j.Snmp4jVarbind.getIndexes(Snmp4jVarbind.java:159) ~[2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.soulwing.snmp.provider.snmp4j.SimpleVarbindFactory.newVarbindCollection(SimpleVarbindFactory.java:65) ~[2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.soulwing.snmp.provider.snmp4j.Snmp4jNotificationEventFactory.newNotification(Snmp4jNotificationEventFactory.java:58) ~[2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.soulwing.snmp.provider.snmp4j.Snmp4jNotificationEventFactory.newEvent(Snmp4jNotificationEventFactory.java:53) ~[2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.soulwing.snmp.provider.snmp4j.Snmp4jListener.processPdu(Snmp4jListener.java:136) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.Snmp$NotificationDispatcher.processPdu(Snmp.java:1934) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.Snmp.fireProcessPdu(Snmp.java:1444) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.Snmp.processPdu(Snmp.java:1215) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.MessageDispatcherImpl.fireProcessPdu(MessageDispatcherImpl.java:675) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.MessageDispatcherImpl.dispatchMessage(MessageDispatcherImpl.java:302) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.MessageDispatcherImpl.processMessage(MessageDispatcherImpl.java:373) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.MessageDispatcherImpl.processMessage(MessageDispatcherImpl.java:333) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.transport.AbstractTransportMapping.fireProcessMessage(AbstractTransportMapping.java:76) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at org.snmp4j.transport.DefaultUdpTransportMapping$ListenThread.run(DefaultUdpTransportMapping.java:414) [2solveAlarmService-1.0.0-jar-with-dependencies.jar:?] at java.lang.Thread.run(Unknown Source) [?:1.8.0_201]

The two oids printed out is the instanceOid and oid in the method.

The exception is being thrown before we enter the listener method. Doing any kind of logging in that method does not print anything. So the saveTrap method is not even being called before the exception occurs.

ceharris commented 5 years ago

Bizarre. If you look at one of the notification definitions in the MIB, you'll see

eriAlarmMinorAlert NOTIFICATION-TYPE
        OBJECTS {
                eriAlarmAlertManagedObject,
                eriAlarmAlertMajorType,
                eriAlarmAlertMinorType,
                eriAlarmAlertSpecificProblem,
                eriAlarmAlertLastSequenceNo,
                eriAlarmAlertEventType,
                eriAlarmAlertEventTime,
                eriAlarmAlertProbableCause,
                eriAlarmNObjAdditionalText,
                eriAlarmNObjMoreAdditionalText,
                eriAlarmNObjResourceId
                }
...

Based on this, you'd expect the notification to contain a sequence of varbinds, each containing an instance of each of these object types (defined elsewhere in the same MIB). But it appears that the varbinds contain type OIDs instead of instance OIDS (which would have an additional index suffix on them). I've never seen this done before, and I think it's actually inconsistent with the SNMPv2-SMI. I think it could be fixed by having MibbleIndexExtractor return an empty array if the OID isn't an instance OID. The empty array would be returned to Snmp4jVarbind.getIndexes, which would then be returned to SimpleVarbindFactory, which would treat them as un-indexed values.

I think that would all work, but we won't know for sure until we try. I can make the necessary changes, and push them to master. Are you able to build the current HEAD of master from source to test on your end when I've got a fix ready?

ceharris commented 5 years ago

I've pushed a fix to master. Let me know if you need help building it from source to test. After you've had a chance to test, we can determine what to do next.

2operate commented 5 years ago

Thank you so much for these fast replies!

I will pull and try and build a a new version and test it.

I will let you know how it goes :)

2operate commented 5 years ago

So it looks much better now!!

However there are still some varbinds that arent being "translated" properly.

Heres the log:

[DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105288917 1.3.6.1.6.3.1.1.4.1.0=[I@22e26c87 1.3.6.1.6.3.18.1.3.0=[B@31c262e3 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498723 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,11:51:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 747 O2/APT "TPBSC1_G17_Q4"U 190320 1151 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP1_TPVMSC2

PLIP PRIP REASON 10.44.72.2 10.44.82.98 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=241084 } } } 2019-03-20 11:51:42,216 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105288917 1.3.6.1.6.3.1.1.4.1.0=[I@22e26c87 1.3.6.1.6.3.18.1.3.0=[B@5adf7283 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498723 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,11:51:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 747 O2/APT "TPBSC1_G17_Q4"U 190320 1151 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP1_TPVMSC2

PLIP PRIP REASON 10.44.72.2 10.44.82.98 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=241084 } } } 2019-03-20 11:51:42,360 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@6e44e04c 2019-03-20 11:51:42,362 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105288917 1.3.6.1.6.3.1.1.4.1.0=[I@55f399cd 1.3.6.1.6.3.18.1.3.0=[B@5ccef2d7 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498723 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 11:51:42,363 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105288917 1.3.6.1.6.3.1.1.4.1.0=[I@55f399cd 1.3.6.1.6.3.18.1.3.0=[B@1e16a578 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498723 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 11:51:42,391 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@6e44e04c 2019-03-20 11:51:45,230 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105289222 1.3.6.1.6.3.1.1.4.1.0=[I@2984423a 1.3.6.1.6.3.18.1.3.0=[B@2f74597 eriAlarmActiveManagedObject=ManagedElement=TPvMME1,SgsnMme=1,PIU=2.1,FaultId=46181312 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498719 eriAlarmActiveSpecificProblem=sctpIPPathFailure eriAlarmActiveEventType=communicationsAlarm eriAlarmActiveEventTime=2019-3-20,11:51:40.0,-3:0 eriAlarmActiveProbableCause=transmitFailure eriAlarmNObjMoreAdditionalText=false eriAlarmNObjAdditionalText=The IP path local IP 10.44.67.61 - remote IP 10.44.104.28 has changed its state to INACTIVE. AssocId is 251 EqPos 2.1, InstId 0. UlpKey is 0x200100fb: HiWord =8193, LoWord(Remote SP)=251. eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=241085 } } } 2019-03-20 11:51:45,231 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105289222 1.3.6.1.6.3.1.1.4.1.0=[I@2984423a 1.3.6.1.6.3.18.1.3.0=[B@29610dd4 eriAlarmActiveManagedObject=ManagedElement=TPvMME1,SgsnMme=1,PIU=2.1,FaultId=46181312 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498719 eriAlarmActiveSpecificProblem=sctpIPPathFailure eriAlarmActiveEventType=communicationsAlarm eriAlarmActiveEventTime=2019-3-20,11:51:40.0,-3:0 eriAlarmActiveProbableCause=transmitFailure eriAlarmNObjMoreAdditionalText=false eriAlarmNObjAdditionalText=The IP path local IP 10.44.67.61 - remote IP 10.44.104.28 has changed its state to INACTIVE. AssocId is 251 EqPos 2.1, InstId 0. UlpKey is 0x200100fb: HiWord =8193, LoWord(Remote SP)=251. eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=241085 } } } 2019-03-20 11:51:45,280 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@6e44e04c

ceharris commented 5 years ago

Great. Glad we improved it.

I think the other problem is being caused by a MIB search ordering problem. I'm making a fix for that now. Will update you shortly.

2operate commented 5 years ago

Awesome :) I really appriciate the help!

ceharris commented 5 years ago

The OIDs that aren't being resolved are part of the SNMPv2-MIB (a.k.a. the RFC1213-MIB). I pushed a change that ensures that MIBs are searched in the reverse order in which they are loaded, which is what is needed since MIBs often import things from previously loaded MIBs. Using the specific MIBs you're loading, the resulting Mib instance can now resolve names for those OIDs. Pull and rebuild and let me know how that works for you.

2operate commented 5 years ago

The log produces this now:

2019-03-20 13:25:44,789 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105853180 1.3.6.1.6.3.1.1.4.1.0=[I@749d94ef 1.3.6.1.6.3.18.1.3.0=[B@36fb9d48 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498989 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,13:25:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 119 O2/APT "TPBSC1_G17_Q4"U 190320 1325 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP2_TPVMSC1

PLIP PRIP REASON 10.44.72.3 10.44.66.114 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=242102 } } } 2019-03-20 13:25:44,798 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105853180 1.3.6.1.6.3.1.1.4.1.0=[I@749d94ef 1.3.6.1.6.3.18.1.3.0=[B@277e01b6 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498989 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,13:25:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 119 O2/APT "TPBSC1_G17_Q4"U 190320 1325 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP2_TPVMSC1

PLIP PRIP REASON 10.44.72.3 10.44.66.114 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=242102 } } } 2019-03-20 13:25:45,410 INFO [main ContextHandler.java:855] - Started o.e.j.s.ServletContextHandler@534c6767{/api,null,AVAILABLE} 2019-03-20 13:25:45,425 INFO [main AbstractConnector.java:292] - Started ServerConnector@360bc645{HTTP/1.1,[http/1.1]}{0.0.0.0:9010} 2019-03-20 13:25:45,425 INFO [main Server.java:407] - Started @2721ms 2019-03-20 13:25:45,605 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@17fce2e4 2019-03-20 13:25:45,606 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105853180 1.3.6.1.6.3.1.1.4.1.0=[I@51c1dfcb 1.3.6.1.6.3.18.1.3.0=[B@2261deaf eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498989 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 13:25:45,607 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105853180 1.3.6.1.6.3.1.1.4.1.0=[I@51c1dfcb 1.3.6.1.6.3.18.1.3.0=[B@76498a9 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498989 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 13:25:45,639 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@17fce2e4 2019-03-20 13:25:53,769 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105854080 1.3.6.1.6.3.1.1.4.1.0=[I@2ebeb0b6 1.3.6.1.6.3.18.1.3.0=[B@2869768 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498986 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,13:25:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 117 O2/APT "TPBSC1_G17_Q4"U 190320 1325 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP1_TPVMSC1

PLIP PRIP REASON 10.44.72.2 10.44.66.98 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=242103 } } } 2019-03-20 13:25:53,770 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105854080 1.3.6.1.6.3.1.1.4.1.0=[I@2ebeb0b6 1.3.6.1.6.3.18.1.3.0=[B@2184e641 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498986 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,13:25:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 117 O2/APT "TPBSC1_G17_Q4"U 190320 1325 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP1_TPVMSC1

PLIP PRIP REASON 10.44.72.2 10.44.66.98 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=242103 } } } 2019-03-20 13:25:53,813 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@17fce2e4 2019-03-20 13:25:53,814 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105854080 1.3.6.1.6.3.1.1.4.1.0=[I@7b8dab05 1.3.6.1.6.3.18.1.3.0=[B@4d865ecb eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498986 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 13:25:53,815 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:66] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105854080 1.3.6.1.6.3.1.1.4.1.0=[I@7b8dab05 1.3.6.1.6.3.18.1.3.0=[B@4bb61fd4 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498986 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-20 13:25:53,840 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@17fce2e4 2019-03-20 13:25:54,770 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ 1.3.6.1.2.1.1.3.0=105854180 1.3.6.1.6.3.1.1.4.1.0=[I@11f16c88 1.3.6.1.6.3.18.1.3.0=[B@6e7e187b eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5498987 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-20,13:25:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 118 O2/APT "TPBSC1_G17_Q4"U 190320 1325 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

Still a few oids left to be translated.

ceharris commented 5 years ago

Interesting. Just to confirm that the name resolution problem in the MIB is fixed, try adding this to your code right after you construct the Mib instance:

logger.debug("test OID-to-instance-name: {}", mib.oidToInstanceName("1.3.6.1.2.1.1.3.0"));

Should output sysUpTime.0.

2operate commented 5 years ago

Hi again :)

I wrote that logger debug line after the MIB has been loaded

Output from log: 2019-03-21 05:35:04,846 DEBUG [main AlarmReceiver.java:61] - test OID-to-instance-name: 1.3.6.1.2.1.1.3.0

ceharris commented 5 years ago

I added a test case that is virtually identical to what you're doing to load the mib and validate it resolvea that OID. Pull and take a look at SimpleVarbindFactoryTest. That test Doesn't fail for me. If you pull and build (mvn install), and it doesn't fail for you, but it doesn't work correctly in your app, I think the most likely cause is that somehow your app is not using the most recent build.

— carl

On Mar 21, 2019, at 4:36 AM, 2operate notifications@github.com wrote:

Hi again :)

I wrote that logger debug line after the MIB has been loaded

Output from log: 2019-03-21 05:35:04,846 DEBUG [main AlarmReceiver.java:61] - test OID-to-instance-name: 1.3.6.1.2.1.1.3.0

— You are receiving this because you commented. Reply to this email directly, view it on GitHub, or mute the thread.

2operate commented 5 years ago

I am going to try something, I will let you know how it goes.

2operate commented 5 years ago

So reason why it didnt get translated before was i messed up with some MIBs. Now I am getting this from the log

2019-03-21 08:32:00,064 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:27.04 internet.6.3.1.1.4.1.0=[I@38a6d347 internet.6.3.18.1.3.0=[B@7b0b459c eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506810 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-21,8:31:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 345 O2/APT "TPBSC1_G17_Q4"U 190321 0831 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP2_TPVMSC1

PLIP PRIP REASON 10.44.72.3 10.44.66.114 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=252728 } } } 2019-03-21 08:32:00,078 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:90] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:27.04 internet.6.3.1.1.4.1.0=[I@38a6d347 internet.6.3.18.1.3.0=[B@67f7af35 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506810 eriAlarmActiveSpecificProblem=SCTP ON CP PREFERRED PRIMARY PATH FAILURE eriAlarmActiveEventType=other eriAlarmActiveEventTime=2019-3-21,8:31:0.0,-3:0 eriAlarmActiveProbableCause=m3100Indeterminate eriAlarmNObjMoreAdditionalText=true eriAlarmNObjAdditionalText=Event Type = Switching; Probable Cause = SWITCHING DEVICES; *** ALARM 345 O2/APT "TPBSC1_G17_Q4"U 190321 0831 SCTP ON CP PREFERRED PRIMARY PATH FAILURE

SAID SCTP2_TPVMSC1

PLIP PRIP REASON 10.44.72.3 10.44.66.114 P eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmActiveLastSequenceNo=252728 } } } 2019-03-21 08:32:00,160 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@160bc405 2019-03-21 08:32:00,161 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:27.04 internet.6.3.1.1.4.1.0=[I@e5f159d internet.6.3.18.1.3.0=[B@2a3aa5c7 eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506810 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-21 08:32:00,164 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:90] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:27.04 internet.6.3.1.1.4.1.0=[I@e5f159d internet.6.3.18.1.3.0=[B@4b6bf78e eriAlarmActiveManagedObject=MeContext=TPBSC1,ManagedElement=TPBSC1 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506810 eriAlarmNObjAdditionalText=PP NOT USED AS APP

END eriAlarmActiveResourceId=0.0 eriAlarmNObjAdditionalInfo= } } } 2019-03-21 08:32:00,194 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@160bc405 2019-03-21 08:32:06,260 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:147] - listener 10.60.219.91/162 notifying handlers for event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:33.31 internet.6.3.1.1.4.1.0=[I@62cc5c68 internet.6.3.18.1.3.0=[B@760f1038 eriAlarmActiveManagedObject=MeContext=TPvIPW2,ManagedElement=TPvIPW2,SystemFunctions=1,Pm=1,PmJob=ENUMTPSDefaultJob,MeasurementReader=mr_1:PL-3 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506812 eriAlarmActiveSpecificProblem=ENUM, Query Failure Error eriAlarmActiveEventType=qualityOfServiceAlarm eriAlarmActiveEventTime=2019-3-21,8:32:1.0,-3:0 eriAlarmActiveProbableCause=x733ThresholdCrossed eriAlarmNObjMoreAdditionalText=false eriAlarmNObjAdditionalText=The alarm is raised when query failures exceed a configured limit (threshold) on expiration of a configured time interval. eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=252729 } } } 2019-03-21 08:32:06,266 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 AlarmReceiver.java:90] - Event: { source=10.60.219.91/162, subject={ type=TRAP peer=10.44.79.95/161 varbinds={ sysUpTime.0=13d 01:08:33.31 internet.6.3.1.1.4.1.0=[I@62cc5c68 internet.6.3.18.1.3.0=[B@bfcd53e eriAlarmActiveManagedObject=MeContext=TPvIPW2,ManagedElement=TPvIPW2,SystemFunctions=1,Pm=1,PmJob=ENUMTPSDefaultJob,MeasurementReader=mr_1:PL-3 eriAlarmActiveMajorType=193 eriAlarmActiveMinorType=5506812 eriAlarmActiveSpecificProblem=ENUM, Query Failure Error eriAlarmActiveEventType=qualityOfServiceAlarm eriAlarmActiveEventTime=2019-3-21,8:32:1.0,-3:0 eriAlarmActiveProbableCause=x733ThresholdCrossed eriAlarmNObjMoreAdditionalText=false eriAlarmNObjAdditionalText=The alarm is raised when query failures exceed a configured limit (threshold) on expiration of a configured time interval. eriAlarmNObjResourceId=false eriAlarmNObjAdditionalInfo= eriAlarmNObjMoreAdditionalInfo=false eriAlarmNObjRecordType=alarmNew eriAlarmActiveLastSequenceNo=252729 } } } 2019-03-21 08:32:06,316 DEBUG [DefaultUDPTransportMapping_10.60.219.91/162 Snmp4jListener.java:156] - event was handled by com._2operate._2solve.alarm.service.receiver.AlarmReceiver$1@160bc405

Only a few oids that arent being translated now.

ceharris commented 5 years ago

So partial names like internet.6.3.1.1.4.1.0 happen when the order in which the MIBs are loaded is incorrect and/or there's overlap between loaded MIBs.

In this particular case, I'm most suspicious of 

mib.load("RFC1155-SMI");
mib.load("RFC1158-MIB");
mib.load("RFC-1212");
mib.load("RFC1213-MIB");

The RFC1155-SMI and RFC1158-MIB, if loaded at all, should be loaded before any of the SNMPv2-* mibs.

RFC1212 is already covered by SNMPv2-TC and shouldn't be loaded at all.

RFC1213-MIB is superseded by SNMPv2-MIB, which should be used in preference, and should be loaded after SNMPv2-TC and before SNMPv2-CONF

RFC1271-MIB is superseded by RMON-MIB, which should be used in preference and is already in the list.

OID 1.3.6.1.6.3.1.1.4.1.0 is defined in SNMPv2-MIB, which isn't being loaded. OID 1.3.6.1.6.3.18.1.3.0 is defined in the SNMP-COMMUNITY-MIB, which isn't being loaded.

A useful technique for figuring out what MIB(s) is/are missing when you see a numeric OID is to google for the OID string. You can then look at the MIB text to see what it imports and make sure you've got it ordered so that all of its dependencies are first.

Taking all this into account, I would load the Mib instance this way:

Mib mib = MibFactory.getInstance().newMib();
mib.load("RFC1155-SMI");
mib.load("RFC1158-MIB");
mib.load("SNMPv2-SMI");
mib.load("SNMPv2-TC");
mib.load("SNMPv2-MIB");                      
mib.load("SNMPv2-CONF");
mib.load("SNMP-FRAMEWORK-MIB");
mib.load("SNMP-COMMUNITY-MIB");
mib.load("SNMP-TARGET-MIB");
mib.load("SNMP-NOTIFICATION-MIB");
mib.load("IANA-ITU-ALARM-TC-MIB");
mib.load("ITU-ALARM-TC-MIB");
mib.load("INET-ADDRESS-MIB");
mib.load("RMON-MIB");
mib.load("TOKEN-RING-RMON-MIB");
mib.load("RMON2-MIB");
mib.load("ALARM-MIB");
mib.load("ERICSSON-TOP-MIB");
mib.load("ERICSSON-TC-MIB");
mib.load("ERICSSON-ALARM-TC-MIB");
mib.load("ERICSSON-ALARM-PC-MIB");
mib.load("ERICSSON-ALARM-MIB");
ceharris commented 5 years ago

BTW, I opened issue #10 to improve the OID-to-name resolution so that the order in which MIBs are loaded is less critical.

2operate commented 5 years ago

With the loading order of the MIBs you proposed it works now :) Everything gets translated properly! Thank you very much for your help. You have saved me from a lot of extra work :)

ceharris commented 5 years ago

Fantastic! Glad to help. I'm going to go ahead and implement the improve described in issue #10 and then I'll make a new release that incorporates the fixes that were put in for this issue and improvement #10. I'll update this issue when the new release has been pushed.

ceharris commented 5 years ago

Version 1.0.9 has been released. Should be available via Maven Central within the next few hours.

endual commented 3 days ago

hi,can you create demo for getting device mac or the cup \mem\disk userate,

thanks a lot